{"level":"warn","ts":"2026-05-29T04:59:46.128706Z","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-29T04:59:46.128792Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-29T04:59:46.128885Z","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-29T04:59:46.1289Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-29T04:59:46.128926Z","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-29T04:59:46.129586Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-05-29T04:59:46.129671Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-29T04:59:46.133472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.597768ms"} {"level":"info","ts":"2026-05-29T04:59:46.145027Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-05-29T04:59:46.145098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T04:59:46.14512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-05-29T04:59:46.145137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T04:59:46.145155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-05-29T04:59:46.145192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-05-29T04:59:46.153823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T04:59:46.15547Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T04:59:46.157127Z","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-29T04:59:46.159119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T04:59:46.16334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T04:59:46.163337Z","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-29T04:59:46.163572Z","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-29T04:59:46.165152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-05-29T04:59:46.165191Z","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-29T04:59:46.165291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-29T04:59:46.167138Z","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-29T04:59:46.167174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-29T04:59:46.16733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-29T04:59:46.167512Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T04:59:46.167534Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T04:59:46.745789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T04:59:46.745842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T04:59:46.745885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-05-29T04:59:46.745906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-05-29T04:59:46.745917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-29T04:59:46.745933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-05-29T04:59:46.745945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-29T04:59:46.747082Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T04:59:46.747233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T04:59:46.747349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T04:59:46.747384Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T04:59:46.748474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T04:59:46.748578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T04:59:46.748607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T04:59:46.750422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T04:59:46.75046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T04:59:46.751078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T04:59:46.751202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-05-29T04:59:57.587983Z","caller":"traceutil/trace.go:171","msg":"trace[1898740603] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"120.92787ms","start":"2026-05-29T04:59:57.467032Z","end":"2026-05-29T04:59:57.58796Z","steps":["trace[1898740603] 'process raft request' (duration: 120.766788ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T04:59:57.845842Z","caller":"traceutil/trace.go:171","msg":"trace[1709473101] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"249.605213ms","start":"2026-05-29T04:59:57.596211Z","end":"2026-05-29T04:59:57.845816Z","steps":["trace[1709473101] 'process raft request' (duration: 249.376721ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T04:59:58.621457Z","caller":"traceutil/trace.go:171","msg":"trace[1651726323] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"381.969785ms","start":"2026-05-29T04:59:58.239466Z","end":"2026-05-29T04:59:58.621436Z","steps":["trace[1651726323] 'process raft request' (duration: 381.847483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T04:59:58.621992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T04:59:58.23945Z","time spent":"382.061525ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T04:59:58.65101Z","caller":"traceutil/trace.go:171","msg":"trace[881763769] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"411.03156ms","start":"2026-05-29T04:59:58.239952Z","end":"2026-05-29T04:59:58.650984Z","steps":["trace[881763769] 'process raft request' (duration: 410.842599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T04:59:58.651178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T04:59:58.239934Z","time spent":"411.166861ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:23.004886Z","caller":"traceutil/trace.go:171","msg":"trace[790962703] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"666.729145ms","start":"2026-05-29T05:00:22.338126Z","end":"2026-05-29T05:00:23.004855Z","steps":["trace[790962703] 'process raft request' (duration: 666.486663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.005204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.338105Z","time spent":"666.860586ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.255795Z","caller":"traceutil/trace.go:171","msg":"trace[1353072867] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"910.972037ms","start":"2026-05-29T05:00:22.344795Z","end":"2026-05-29T05:00:23.255767Z","steps":["trace[1353072867] 'process raft request' (duration: 910.367922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.255929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.344776Z","time spent":"911.091518ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256159Z","caller":"traceutil/trace.go:171","msg":"trace[1691935755] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"881.478614ms","start":"2026-05-29T05:00:22.374667Z","end":"2026-05-29T05:00:23.256145Z","steps":["trace[1691935755] 'process raft request' (duration: 881.183131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.374654Z","time spent":"881.551284ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256256Z","caller":"traceutil/trace.go:171","msg":"trace[1035053102] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"901.966413ms","start":"2026-05-29T05:00:22.354275Z","end":"2026-05-29T05:00:23.256241Z","steps":["trace[1035053102] 'process raft request' (duration: 901.308968ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.256332Z","caller":"traceutil/trace.go:171","msg":"trace[477395033] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"890.812411ms","start":"2026-05-29T05:00:22.365505Z","end":"2026-05-29T05:00:23.256318Z","steps":["trace[477395033] 'process raft request' (duration: 890.307657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.365497Z","time spent":"890.865741ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256138Z","caller":"traceutil/trace.go:171","msg":"trace[101853779] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"898.347753ms","start":"2026-05-29T05:00:22.35774Z","end":"2026-05-29T05:00:23.256087Z","steps":["trace[101853779] 'process raft request' (duration: 898.03083ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.256348Z","caller":"traceutil/trace.go:171","msg":"trace[727207378] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"881.487313ms","start":"2026-05-29T05:00:22.374717Z","end":"2026-05-29T05:00:23.256205Z","steps":["trace[727207378] 'process raft request' (duration: 881.215691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.354242Z","time spent":"902.059844ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:00:23.256496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.357735Z","time spent":"898.736556ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256578Z","caller":"traceutil/trace.go:171","msg":"trace[453442203] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"874.113762ms","start":"2026-05-29T05:00:22.382456Z","end":"2026-05-29T05:00:23.256569Z","steps":["trace[453442203] 'process raft request' (duration: 873.676698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.25662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.382448Z","time spent":"874.154492ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:00:23.256596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.374714Z","time spent":"881.799716ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256693Z","caller":"traceutil/trace.go:171","msg":"trace[6458578] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"878.726221ms","start":"2026-05-29T05:00:22.377957Z","end":"2026-05-29T05:00:23.256683Z","steps":["trace[6458578] 'process raft request' (duration: 878.052555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.37795Z","time spent":"878.767471ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256672Z","caller":"traceutil/trace.go:171","msg":"trace[791634056] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:499; }","duration":"727.674321ms","start":"2026-05-29T05:00:22.528971Z","end":"2026-05-29T05:00:23.256645Z","steps":["trace[791634056] 'read index received' (duration: 475.716845ms)","trace[791634056] 'applied index is now lower than readState.Index' (duration: 251.956606ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:23.256755Z","caller":"traceutil/trace.go:171","msg":"trace[947153529] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"899.044908ms","start":"2026-05-29T05:00:22.357683Z","end":"2026-05-29T05:00:23.256728Z","steps":["trace[947153529] 'process raft request' (duration: 897.9822ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.256812Z","caller":"traceutil/trace.go:171","msg":"trace[358958177] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"842.471661ms","start":"2026-05-29T05:00:22.414332Z","end":"2026-05-29T05:00:23.256804Z","steps":["trace[358958177] 'process raft request' (duration: 842.031857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.35767Z","time spent":"899.1243ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.256841Z","caller":"traceutil/trace.go:171","msg":"trace[1577379918] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"739.501519ms","start":"2026-05-29T05:00:22.517326Z","end":"2026-05-29T05:00:23.256827Z","steps":["trace[1577379918] 'process raft request' (duration: 739.202547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.414307Z","time spent":"842.530711ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:23.256847Z","caller":"traceutil/trace.go:171","msg":"trace[1428991476] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"844.83478ms","start":"2026-05-29T05:00:22.411998Z","end":"2026-05-29T05:00:23.256833Z","steps":["trace[1428991476] 'process raft request' (duration: 844.277036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.256963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.411971Z","time spent":"844.961091ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:00:23.257004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.517296Z","time spent":"739.60414ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:00:23.257032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.056975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:00:23.257143Z","caller":"traceutil/trace.go:171","msg":"trace[2067811218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"728.169766ms","start":"2026-05-29T05:00:22.528962Z","end":"2026-05-29T05:00:23.257131Z","steps":["trace[2067811218] 'agreement among raft nodes before linearized reading' (duration: 728.038975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.257189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.528905Z","time spent":"728.268626ms","remote":"127.0.0.1:38002","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-05-29T05:00:23.413736Z","caller":"traceutil/trace.go:171","msg":"trace[519603886] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"401.565619ms","start":"2026-05-29T05:00:23.012139Z","end":"2026-05-29T05:00:23.413705Z","steps":["trace[519603886] 'process raft request' (duration: 381.371392ms)","trace[519603886] 'compare' (duration: 19.939845ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:23.413908Z","caller":"traceutil/trace.go:171","msg":"trace[1479921526] transaction","detail":"{read_only:false; number_of_response:1; response_revision:497; }","duration":"401.434797ms","start":"2026-05-29T05:00:23.012454Z","end":"2026-05-29T05:00:23.413889Z","steps":["trace[1479921526] 'process raft request' (duration: 401.342876ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.413921Z","caller":"traceutil/trace.go:171","msg":"trace[1278474951] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"401.713269ms","start":"2026-05-29T05:00:23.012189Z","end":"2026-05-29T05:00:23.413902Z","steps":["trace[1278474951] 'process raft request' (duration: 401.394647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.413925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:23.012117Z","time spent":"401.732ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:00:23.414013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:23.012445Z","time spent":"401.521508ms","remote":"127.0.0.1:38048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:00:23.414041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:23.012166Z","time spent":"401.81483ms","remote":"127.0.0.1:38526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:23.413891Z","caller":"traceutil/trace.go:171","msg":"trace[604712858] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:512; }","duration":"157.090004ms","start":"2026-05-29T05:00:23.256774Z","end":"2026-05-29T05:00:23.413865Z","steps":["trace[604712858] 'read index received' (duration: 136.796597ms)","trace[604712858] 'applied index is now lower than readState.Index' (duration: 20.292317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:00:23.414205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.6467ms","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-29T05:00:23.414244Z","caller":"traceutil/trace.go:171","msg":"trace[244764383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"667.73441ms","start":"2026-05-29T05:00:22.746499Z","end":"2026-05-29T05:00:23.414234Z","steps":["trace[244764383] 'agreement among raft nodes before linearized reading' (duration: 667.626569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.414299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.74648Z","time spent":"667.81152ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:00:23.414379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.128989ms","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-29T05:00:23.414455Z","caller":"traceutil/trace.go:171","msg":"trace[1839332557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"682.294441ms","start":"2026-05-29T05:00:22.732148Z","end":"2026-05-29T05:00:23.414442Z","steps":["trace[1839332557] 'agreement among raft nodes before linearized reading' (duration: 682.034399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.414489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:22.732091Z","time spent":"682.388501ms","remote":"127.0.0.1:38282","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":"info","ts":"2026-05-29T05:00:23.414878Z","caller":"traceutil/trace.go:171","msg":"trace[481882773] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"134.008574ms","start":"2026-05-29T05:00:23.280857Z","end":"2026-05-29T05:00:23.414866Z","steps":["trace[481882773] 'process raft request' (duration: 133.911103ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.414924Z","caller":"traceutil/trace.go:171","msg":"trace[1155298597] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"147.209623ms","start":"2026-05-29T05:00:23.267707Z","end":"2026-05-29T05:00:23.414917Z","steps":["trace[1155298597] 'process raft request' (duration: 146.777039ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.415035Z","caller":"traceutil/trace.go:171","msg":"trace[1955555255] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"144.993385ms","start":"2026-05-29T05:00:23.270032Z","end":"2026-05-29T05:00:23.415026Z","steps":["trace[1955555255] 'process raft request' (duration: 144.550811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.415063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.225356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:00:23.415104Z","caller":"traceutil/trace.go:171","msg":"trace[2003151568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"151.281316ms","start":"2026-05-29T05:00:23.263811Z","end":"2026-05-29T05:00:23.415093Z","steps":["trace[2003151568] 'agreement among raft nodes before linearized reading' (duration: 151.209815ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.415145Z","caller":"traceutil/trace.go:171","msg":"trace[792053574] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"144.369349ms","start":"2026-05-29T05:00:23.270765Z","end":"2026-05-29T05:00:23.415134Z","steps":["trace[792053574] 'process raft request' (duration: 143.858145ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.414891Z","caller":"traceutil/trace.go:171","msg":"trace[874851127] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"147.044591ms","start":"2026-05-29T05:00:23.267835Z","end":"2026-05-29T05:00:23.414879Z","steps":["trace[874851127] 'process raft request' (duration: 146.714729ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.415281Z","caller":"traceutil/trace.go:171","msg":"trace[1928404595] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"138.166059ms","start":"2026-05-29T05:00:23.277108Z","end":"2026-05-29T05:00:23.415274Z","steps":["trace[1928404595] 'process raft request' (duration: 137.569844ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.415294Z","caller":"traceutil/trace.go:171","msg":"trace[244745586] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"132.488522ms","start":"2026-05-29T05:00:23.282794Z","end":"2026-05-29T05:00:23.415283Z","steps":["trace[244745586] 'process raft request' (duration: 132.029578ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:23.415217Z","caller":"traceutil/trace.go:171","msg":"trace[695792855] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"138.3927ms","start":"2026-05-29T05:00:23.276817Z","end":"2026-05-29T05:00:23.41521Z","steps":["trace[695792855] 'process raft request' (duration: 137.830445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:23.414628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.442082ms","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-29T05:00:23.417643Z","caller":"traceutil/trace.go:171","msg":"trace[1993894959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"208.544098ms","start":"2026-05-29T05:00:23.209028Z","end":"2026-05-29T05:00:23.417572Z","steps":["trace[1993894959] 'agreement among raft nodes before linearized reading' (duration: 204.901929ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:29.160657Z","caller":"traceutil/trace.go:171","msg":"trace[868477746] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"620.573091ms","start":"2026-05-29T05:00:28.540029Z","end":"2026-05-29T05:00:29.160602Z","steps":["trace[868477746] 'process raft request' (duration: 617.848881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.161224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:28.540005Z","time spent":"620.735677ms","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:29.735662Z","caller":"traceutil/trace.go:171","msg":"trace[39402800] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"256.574183ms","start":"2026-05-29T05:00:29.479028Z","end":"2026-05-29T05:00:29.735602Z","steps":["trace[39402800] 'process raft request' (duration: 256.23266ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:29.888907Z","caller":"traceutil/trace.go:171","msg":"trace[447448707] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"409.740661ms","start":"2026-05-29T05:00:29.479128Z","end":"2026-05-29T05:00:29.888868Z","steps":["trace[447448707] 'process raft request' (duration: 409.476721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.889093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:29.4791Z","time spent":"409.903667ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:29.891101Z","caller":"traceutil/trace.go:171","msg":"trace[1049802556] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"402.139653ms","start":"2026-05-29T05:00:29.488937Z","end":"2026-05-29T05:00:29.891077Z","steps":["trace[1049802556] 'read index received' (duration: 246.838797ms)","trace[1049802556] 'applied index is now lower than readState.Index' (duration: 155.300036ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:29.89133Z","caller":"traceutil/trace.go:171","msg":"trace[1242235434] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"238.323345ms","start":"2026-05-29T05:00:29.652984Z","end":"2026-05-29T05:00:29.891307Z","steps":["trace[1242235434] 'process raft request' (duration: 238.044305ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:29.891332Z","caller":"traceutil/trace.go:171","msg":"trace[2058021143] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"258.66997ms","start":"2026-05-29T05:00:29.632596Z","end":"2026-05-29T05:00:29.891266Z","steps":["trace[2058021143] 'process raft request' (duration: 257.082631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.891456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.500066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T05:00:29.891496Z","caller":"traceutil/trace.go:171","msg":"trace[680620094] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:592; }","duration":"402.587008ms","start":"2026-05-29T05:00:29.488898Z","end":"2026-05-29T05:00:29.891485Z","steps":["trace[680620094] 'agreement among raft nodes before linearized reading' (duration: 402.478015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.891528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:29.488877Z","time spent":"402.642521ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T05:00:29.891709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.39049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:00:29.891738Z","caller":"traceutil/trace.go:171","msg":"trace[52807935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"361.504754ms","start":"2026-05-29T05:00:29.530224Z","end":"2026-05-29T05:00:29.891729Z","steps":["trace[52807935] 'agreement among raft nodes before linearized reading' (duration: 361.463573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.89176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:29.530187Z","time spent":"361.568226ms","remote":"127.0.0.1:38002","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-05-29T05:00:29.891873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.400467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T05:00:29.891909Z","caller":"traceutil/trace.go:171","msg":"trace[1031672612] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:592; }","duration":"353.46233ms","start":"2026-05-29T05:00:29.538435Z","end":"2026-05-29T05:00:29.891897Z","steps":["trace[1031672612] 'agreement among raft nodes before linearized reading' (duration: 353.396367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:29.891937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:29.538419Z","time spent":"353.511992ms","remote":"127.0.0.1:38024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-05-29T05:00:30.572775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669432,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:00:30.580051Z","caller":"traceutil/trace.go:171","msg":"trace[2036073989] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"508.413397ms","start":"2026-05-29T05:00:30.071607Z","end":"2026-05-29T05:00:30.58002Z","steps":["trace[2036073989] 'read index received' (duration: 508.21796ms)","trace[2036073989] 'applied index is now lower than readState.Index' (duration: 194.307µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:30.580148Z","caller":"traceutil/trace.go:171","msg":"trace[306966156] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"637.309129ms","start":"2026-05-29T05:00:29.942818Z","end":"2026-05-29T05:00:30.580127Z","steps":["trace[306966156] 'process raft request' (duration: 637.06749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:30.580248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:29.942771Z","time spent":"637.430723ms","remote":"127.0.0.1:38514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:00:30.580294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.664126ms","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-29T05:00:30.580352Z","caller":"traceutil/trace.go:171","msg":"trace[1599373514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"508.765759ms","start":"2026-05-29T05:00:30.071572Z","end":"2026-05-29T05:00:30.580337Z","steps":["trace[1599373514] 'agreement among raft nodes before linearized reading' (duration: 508.529841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:30.580402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:30.071556Z","time spent":"508.834382ms","remote":"127.0.0.1:38282","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-05-29T05:00:31.240076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:00:31.740517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:00:32.241541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:00:32.740128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000049616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:00:32.740267Z","caller":"traceutil/trace.go:171","msg":"trace[188821322] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000221464s","start":"2026-05-29T05:00:30.740021Z","end":"2026-05-29T05:00:32.740242Z","steps":["trace[188821322] 'agreement among raft nodes before linearized reading' (duration: 2.000044037s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:32.740355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:30.739974Z","time spent":"2.000366498s","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:00:32.742406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:00:33.243364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:00:33.292875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.625333424s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T05:00:33.531628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000713438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:00:33.531705Z","caller":"traceutil/trace.go:171","msg":"trace[211390258] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000841233s","start":"2026-05-29T05:00:31.530848Z","end":"2026-05-29T05:00:33.53169Z","steps":["trace[211390258] 'agreement among raft nodes before linearized reading' (duration: 2.00074846s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.531747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:31.53083Z","time spent":"2.000909205s","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:00:33.581613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.190703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:00:33.58232Z","caller":"traceutil/trace.go:171","msg":"trace[1157382209] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"2.914909667s","start":"2026-05-29T05:00:30.667383Z","end":"2026-05-29T05:00:33.582293Z","steps":["trace[1157382209] 'process raft request' (duration: 2.625837852s)","trace[1157382209] 'compare' (duration: 287.108206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:00:33.582442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:30.667361Z","time spent":"2.91501183s","remote":"127.0.0.1:38202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:33.582502Z","caller":"traceutil/trace.go:171","msg":"trace[1968677210] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.547853569s","start":"2026-05-29T05:00:31.034631Z","end":"2026-05-29T05:00:33.582485Z","steps":["trace[1968677210] 'process raft request' (duration: 2.547254368s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.582581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:31.034605Z","time spent":"2.547937692s","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:33.582872Z","caller":"traceutil/trace.go:171","msg":"trace[345946144] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"839.780213ms","start":"2026-05-29T05:00:32.74308Z","end":"2026-05-29T05:00:33.582861Z","steps":["trace[345946144] 'process raft request' (duration: 839.362529ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:33.582902Z","caller":"traceutil/trace.go:171","msg":"trace[1565604911] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"129.92633ms","start":"2026-05-29T05:00:33.452963Z","end":"2026-05-29T05:00:33.582889Z","steps":["trace[1565604911] 'process raft request' (duration: 129.802496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.58293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:32.74306Z","time spent":"839.845685ms","remote":"127.0.0.1:38072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:00:33.583072Z","caller":"traceutil/trace.go:171","msg":"trace[873137742] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:618; }","duration":"2.843027273s","start":"2026-05-29T05:00:30.740035Z","end":"2026-05-29T05:00:33.583062Z","steps":["trace[873137742] 'read index received' (duration: 2.553199882s)","trace[873137742] 'applied index is now lower than readState.Index' (duration: 289.826431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:00:33.583285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103801969s","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-29T05:00:33.583322Z","caller":"traceutil/trace.go:171","msg":"trace[805162548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"2.103871571s","start":"2026-05-29T05:00:31.479441Z","end":"2026-05-29T05:00:33.583313Z","steps":["trace[805162548] 'agreement among raft nodes before linearized reading' (duration: 2.103747887s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.58335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:31.479423Z","time spent":"2.103920263s","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:00:33.583638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.330918ms","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-29T05:00:33.583676Z","caller":"traceutil/trace.go:171","msg":"trace[1424753804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"976.400492ms","start":"2026-05-29T05:00:32.607267Z","end":"2026-05-29T05:00:33.583667Z","steps":["trace[1424753804] 'agreement among raft nodes before linearized reading' (duration: 976.307838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.583703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:32.60725Z","time spent":"976.446403ms","remote":"127.0.0.1:38282","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-05-29T05:00:33.583836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690565123s","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-29T05:00:33.583867Z","caller":"traceutil/trace.go:171","msg":"trace[2052658556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.690594574s","start":"2026-05-29T05:00:31.893264Z","end":"2026-05-29T05:00:33.583859Z","steps":["trace[2052658556] 'agreement among raft nodes before linearized reading' (duration: 1.690517752s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.583928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:31.893206Z","time spent":"1.690685887s","remote":"127.0.0.1:38282","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-29T05:00:33.584028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844505444s","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-29T05:00:33.584059Z","caller":"traceutil/trace.go:171","msg":"trace[771638597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.844568057s","start":"2026-05-29T05:00:31.739483Z","end":"2026-05-29T05:00:33.584051Z","steps":["trace[771638597] 'agreement among raft nodes before linearized reading' (duration: 1.844498254s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:33.584091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:31.739439Z","time spent":"1.844646179s","remote":"127.0.0.1:38282","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":"warn","ts":"2026-05-29T05:00:33.833789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.258022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:00:33.834101Z","caller":"traceutil/trace.go:171","msg":"trace[2100790931] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"170.702358ms","start":"2026-05-29T05:00:33.663332Z","end":"2026-05-29T05:00:33.834034Z","steps":["trace[2100790931] 'process raft request' (duration: 32.064443ms)","trace[2100790931] 'compare' (duration: 138.126428ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:33.834188Z","caller":"traceutil/trace.go:171","msg":"trace[204899282] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"169.230668ms","start":"2026-05-29T05:00:33.664693Z","end":"2026-05-29T05:00:33.833924Z","steps":["trace[204899282] 'read index received' (duration: 30.724617ms)","trace[204899282] 'applied index is now lower than readState.Index' (duration: 138.504891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:00:33.83435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.636642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:00:33.834455Z","caller":"traceutil/trace.go:171","msg":"trace[735165013] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:606; }","duration":"169.765346ms","start":"2026-05-29T05:00:33.664673Z","end":"2026-05-29T05:00:33.834438Z","steps":["trace[735165013] 'agreement among raft nodes before linearized reading' (duration: 169.516987ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:33.849525Z","caller":"traceutil/trace.go:171","msg":"trace[460134396] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"176.648268ms","start":"2026-05-29T05:00:33.672853Z","end":"2026-05-29T05:00:33.849502Z","steps":["trace[460134396] 'process raft request' (duration: 176.315186ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:36.185174Z","caller":"traceutil/trace.go:171","msg":"trace[1789392725] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"147.345075ms","start":"2026-05-29T05:00:36.0378Z","end":"2026-05-29T05:00:36.185145Z","steps":["trace[1789392725] 'process raft request' (duration: 147.024604ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:38.888992Z","caller":"traceutil/trace.go:171","msg":"trace[212039115] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"162.894204ms","start":"2026-05-29T05:00:38.726077Z","end":"2026-05-29T05:00:38.888971Z","steps":["trace[212039115] 'process raft request' (duration: 162.753379ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:38.890205Z","caller":"traceutil/trace.go:171","msg":"trace[401730834] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"143.14073ms","start":"2026-05-29T05:00:38.747054Z","end":"2026-05-29T05:00:38.890194Z","steps":["trace[401730834] 'process raft request' (duration: 143.089358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:51.031324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020727061669772,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:00:51.186901Z","caller":"traceutil/trace.go:171","msg":"trace[1487308211] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"764.70502ms","start":"2026-05-29T05:00:50.422169Z","end":"2026-05-29T05:00:51.186874Z","steps":["trace[1487308211] 'process raft request' (duration: 764.547896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:51.187048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:50.422143Z","time spent":"764.834714ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:51.422051Z","caller":"traceutil/trace.go:171","msg":"trace[718018411] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"891.018563ms","start":"2026-05-29T05:00:50.531008Z","end":"2026-05-29T05:00:51.422026Z","steps":["trace[718018411] 'read index received' (duration: 655.741949ms)","trace[718018411] 'applied index is now lower than readState.Index' (duration: 235.275664ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:51.422166Z","caller":"traceutil/trace.go:171","msg":"trace[964475791] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"644.954474ms","start":"2026-05-29T05:00:50.777179Z","end":"2026-05-29T05:00:51.422134Z","steps":["trace[964475791] 'process raft request' (duration: 644.730977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:51.42219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.183048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:00:51.422336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:50.777153Z","time spent":"645.089318ms","remote":"127.0.0.1:38260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:51.422373Z","caller":"traceutil/trace.go:171","msg":"trace[1148054847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"891.373793ms","start":"2026-05-29T05:00:50.53095Z","end":"2026-05-29T05:00:51.422324Z","steps":["trace[1148054847] 'agreement among raft nodes before linearized reading' (duration: 891.167387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:51.42245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:50.530931Z","time spent":"891.494217ms","remote":"127.0.0.1:38002","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-05-29T05:00:55.174113Z","caller":"traceutil/trace.go:171","msg":"trace[1042061544] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"331.875017ms","start":"2026-05-29T05:00:54.842218Z","end":"2026-05-29T05:00:55.174093Z","steps":["trace[1042061544] 'process raft request' (duration: 331.635121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:55.174248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:00:54.842196Z","time spent":"331.982901ms","remote":"127.0.0.1:38202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4113,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:00:55.452995Z","caller":"traceutil/trace.go:171","msg":"trace[827061895] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"161.988987ms","start":"2026-05-29T05:00:55.290978Z","end":"2026-05-29T05:00:55.452967Z","steps":["trace[827061895] 'process raft request' (duration: 146.453224ms)","trace[827061895] 'compare' (duration: 15.41423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:00:56.200708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.104519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-05-29T05:00:56.200852Z","caller":"traceutil/trace.go:171","msg":"trace[120919321] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:820; }","duration":"153.217482ms","start":"2026-05-29T05:00:56.047564Z","end":"2026-05-29T05:00:56.200782Z","steps":["trace[120919321] 'range keys from in-memory index tree' (duration: 152.128582ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:56.523381Z","caller":"traceutil/trace.go:171","msg":"trace[659956975] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"139.393859ms","start":"2026-05-29T05:00:56.38393Z","end":"2026-05-29T05:00:56.523324Z","steps":["trace[659956975] 'read index received' (duration: 139.160603ms)","trace[659956975] 'applied index is now lower than readState.Index' (duration: 232.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:56.52342Z","caller":"traceutil/trace.go:171","msg":"trace[2128109117] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"146.076144ms","start":"2026-05-29T05:00:56.377308Z","end":"2026-05-29T05:00:56.523384Z","steps":["trace[2128109117] 'process raft request' (duration: 145.835787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:00:56.524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.026047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-05-29T05:00:56.52407Z","caller":"traceutil/trace.go:171","msg":"trace[477748105] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:821; }","duration":"140.160441ms","start":"2026-05-29T05:00:56.38389Z","end":"2026-05-29T05:00:56.52405Z","steps":["trace[477748105] 'agreement among raft nodes before linearized reading' (duration: 139.563705ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:00:56.705854Z","caller":"traceutil/trace.go:171","msg":"trace[373276841] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"167.979719ms","start":"2026-05-29T05:00:56.537849Z","end":"2026-05-29T05:00:56.705829Z","steps":["trace[373276841] 'process raft request' (duration: 140.660685ms)","trace[373276841] 'compare' (duration: 27.140239ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:00:57.347533Z","caller":"traceutil/trace.go:171","msg":"trace[284969705] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"114.963363ms","start":"2026-05-29T05:00:57.232546Z","end":"2026-05-29T05:00:57.34751Z","steps":["trace[284969705] 'process raft request' (duration: 114.345257ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:01:06.28057Z","caller":"traceutil/trace.go:171","msg":"trace[799909411] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"376.330214ms","start":"2026-05-29T05:01:05.904219Z","end":"2026-05-29T05:01:06.280549Z","steps":["trace[799909411] 'read index received' (duration: 376.13758ms)","trace[799909411] 'applied index is now lower than readState.Index' (duration: 191.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:01:06.280587Z","caller":"traceutil/trace.go:171","msg":"trace[1109883194] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"394.788023ms","start":"2026-05-29T05:01:05.885778Z","end":"2026-05-29T05:01:06.280566Z","steps":["trace[1109883194] 'process raft request' (duration: 394.65939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:06.280711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:05.885749Z","time spent":"394.902986ms","remote":"127.0.0.1:38072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:01:06.280901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.668134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-05-29T05:01:06.280963Z","caller":"traceutil/trace.go:171","msg":"trace[400128488] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:933; }","duration":"376.776966ms","start":"2026-05-29T05:01:05.904173Z","end":"2026-05-29T05:01:06.28095Z","steps":["trace[400128488] 'agreement among raft nodes before linearized reading' (duration: 376.496538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:06.280992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:05.904159Z","time spent":"376.828357ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1486,"request content":"key:\"/registry/configmaps/local-path-storage/local-path-config\" "} {"level":"info","ts":"2026-05-29T05:01:06.638035Z","caller":"traceutil/trace.go:171","msg":"trace[1756403141] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"311.624395ms","start":"2026-05-29T05:01:06.326387Z","end":"2026-05-29T05:01:06.638012Z","steps":["trace[1756403141] 'read index received' (duration: 311.43475ms)","trace[1756403141] 'applied index is now lower than readState.Index' (duration: 188.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:01:06.638096Z","caller":"traceutil/trace.go:171","msg":"trace[1831481107] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"349.63442ms","start":"2026-05-29T05:01:06.288427Z","end":"2026-05-29T05:01:06.638062Z","steps":["trace[1831481107] 'process raft request' (duration: 349.448676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:06.638201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.784649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:01:06.6383Z","caller":"traceutil/trace.go:171","msg":"trace[1730217473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"311.871411ms","start":"2026-05-29T05:01:06.326373Z","end":"2026-05-29T05:01:06.638244Z","steps":["trace[1730217473] 'agreement among raft nodes before linearized reading' (duration: 311.763348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:01:06.638319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:06.288413Z","time spent":"349.758284ms","remote":"127.0.0.1:38202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:01:06.638355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:01:06.32636Z","time spent":"311.983405ms","remote":"127.0.0.1:38002","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-05-29T05:01:06.641036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.467348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:01:06.641146Z","caller":"traceutil/trace.go:171","msg":"trace[936348646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"110.613722ms","start":"2026-05-29T05:01:06.530512Z","end":"2026-05-29T05:01:06.641125Z","steps":["trace[936348646] 'agreement among raft nodes before linearized reading' (duration: 107.705598ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:01:07.111319Z","caller":"traceutil/trace.go:171","msg":"trace[1537629901] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"145.145876ms","start":"2026-05-29T05:01:06.966152Z","end":"2026-05-29T05:01:07.111298Z","steps":["trace[1537629901] 'process raft request' (duration: 145.023943ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:02:06.411827Z","caller":"traceutil/trace.go:171","msg":"trace[1012687175] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"218.519977ms","start":"2026-05-29T05:02:06.193278Z","end":"2026-05-29T05:02:06.411798Z","steps":["trace[1012687175] 'process raft request' (duration: 218.362225ms)"],"step_count":1}