Closed stuberman closed 1 year ago
Additional logs after another panic running -vv flag
2022-12-20T23:47:35.048Z DEBUG dagstore dagstore/wrapper.go:169 trace {"shard-key": "baga6ea4seaqcpzadudc7i3bard5ihiqi7j4qbm6mzadsunkg7gxo3cykhlhoeia", "op-type": "OpShardRelease", "after": "ShardStateAvailable"} unexpected fault address 0x0 fatal error: fault [signal SIGSEGV: segmentation violation code=0x80 addr=0x0 pc=0x1cb99fd]
goroutine 914290 [running]: runtime.throw({0x3ba206f?, 0x38abdc0?}) /usr/local/go/src/runtime/panic.go:992 +0x71 fp=0xc00e2d7328 sp=0xc00e2d72f8 pc=0x6afcd1 runtime.sigpanic() /usr/local/go/src/runtime/signal_unix.go:825 +0x305 fp=0xc00e2d7378 sp=0xc00e2d7328 pc=0x6c61a5 github.com/filecoin-project/go-data-transfer/channels.channelState.Voucher({{0xc00f5e0b70, 0x26}, 0x0, {{0x6, 0x1}}, 0x40000, {0x40000, 0x0}, {0x0, 0x1}, ...}) /home/stuart/go/pkg/mod/github.com/filecoin-project/go-data-transfer@v1.15.2/channels/channel_state.go:104 +0x3d fp=0xc00e2d73b0 sp=0xc00e2d7378 pc=0x1cb99fd github.com/filecoin-project/go-data-transfer/channels.(*channelState).Voucher(0x3897d80?)
@stuberman thanks for the bug report. It looks like an issue in the underlying json-rpc library that boost uses to call out to lotus
Closing in favour of https://github.com/filecoin-project/go-jsonrpc/issues/87
Reopening - I misdiagnosed the source of the panic. Looks like it's actually coming from this line in go-data-transfer: https://github.com/filecoin-project/go-data-transfer/blob/v1.15.2/channels/channel_state.go#L105
Fixed in https://github.com/filecoin-project/go-data-transfer/pull/354 and merged into boost in https://github.com/filecoin-project/boost/pull/1056
Checklist
Latest release
, or the most recent RC(release canadiate) for the upcoming release or the dev branch(master), or have an issue updating to any of these.Boost component
Boost Version
Describe the Bug
Deals from Estuary fail to download/transfer
Logging Information
Repo Steps
Boost log from storage deals UI
f3vnq2cmwig3qjisnx5hobxvsd4drn4f54xfxnv4tciw6vnjdsf5xipgafreprh5riwmgtcirpcdmi3urbg36a 12D3KooWCVXs8P7iq6ao4XhfAmKWrEeuKFWCJgqe9jGDMTqHYBjw bafyreibli4jlspyon2oxvqgnnl7rdjheqxw7scvlnfyfd5slnbody2smem bafy2bzacebb22db5ijn5im5ggg5vnez2j64njmj47dh5jr5gal5x5zt7ec4cu Yes baga6ea4seaqc7yeevk2lkx5ci3rc4keh7vhq6xg4kcifkfqdnoklzy3n4v3uyba 16 GiB (17,179,869,184 bytes) 0 atto 0.005 FIL 0 atto 2,442,417 2,462,546 (in 7 days) 3,957,266 (in a year) 1,494,720 (519 days) 0 atto (Price per epoch x Piece Size x Duration) Online libp2p 8.8 GiB (9,438,568,839 bytes) URL: libp2p:///ip4/147.28.156.33/tcp/6745/p2p/12D3KooWQt6XqZ5XMkUiSqnfekep2X5o1GQxxKkKWj2mg1QYBXco 61 B (61 bytes) /market/.boost/incoming/33316998-f823-4ada-920a-ba9cf5b442de.download Accepted (15m ago) Transfer stalled at 0%
CreatedAt 2022-12-20 18:13:24.230 (15m ago) Client Address f3vnq2cmwig3qjisnx5hobxvsd4drn4f54xfxnv4tciw6vnjdsf5xipgafreprh5riwmgtcirpcdmi3urbg36a Client Peer ID 12D3KooWCVXs8P7iq6ao4XhfAmKWrEeuKFWCJgqe9jGDMTqHYBjw Signed Proposal CID bafyreibli4jlspyon2oxvqgnnl7rdjheqxw7scvlnfyfd5slnbody2smem Deal Data Root CID bafy2bzacebb22db5ijn5im5ggg5vnez2j64njmj47dh5jr5gal5x5zt7ec4cu Verified Yes Piece CID baga6ea4seaqc7yeevk2lkx5ci3rc4keh7vhq6xg4kcifkfqdnoklzy3n4v3uyba Piece Size 16 GiB (17,179,869,184 bytes) Client Collateral 0 atto Provider Collateral 0.005 FIL Storage Price / epoch / GiB 0 atto Current Epoch 2,442,417 Start Epoch 2,462,546 (in 7 days) End Epoch 3,957,266 (in a year) Duration 1,494,720 (519 days) Storage Fee 0 atto (Price per epoch x Piece Size x Duration) Transfer Mode Online Transfer Type libp2p Transfer Size 8.8 GiB (9,438,568,839 bytes) Transfer Params URL: libp2p:///ip4/147.28.156.33/tcp/6745/p2p/12D3KooWQt6XqZ5XMkUiSqnfekep2X5o1GQxxKkKWj2mg1QYBXco Transferred 61 B (61 bytes) Inbound File Path /market/.boost/incoming/33316998-f823-4ada-920a-ba9cf5b442de.download Publish Message CID Chain Deal ID
Checkpoint Accepted (15m ago) Status Transfer stalled at 0% Cancel Transfer Deal Logs 2022-12-20 18:13:23.474
executing deal proposal received from network peer: 12D3KooWCVXs8P7iq6ao4XhfAmKWrEeuKFWCJgqe9jGDMTqHYBjw 2022-12-20 18:13:23.510 36ms
processing deal acceptance request 2022-12-20 18:13:24.215 705ms
tagged funds for deal tagged for deal collateral: 4,832,551,957,390,968 tagged for deal publish: 150,000,000,000,000,000 total available for collateral: 25,188,471,048,120,140,580 total available for publish: 49,708,386,566,633,845,217 total tagged for collateral: 13,536,750,656,746,547 total tagged for publish: 300,000,000,000,000,000 2022-12-20 18:13:24.226 11ms
created deal download staging file path: /market/.boost/incoming/33316998-f823-4ada-920a-ba9cf5b442de.download 2022-12-20 18:13:24.235 9ms inserted deal into deals DB 2022-12-20 18:13:24.239 4ms deal accepted and scheduled for execution 2022-12-20 18:13:24.239 0ms deal execution initiated deal state: 2022-12-20 18:13:24.252 13ms
size of downloaded file filepath: /market/.boost/incoming/33316998-f823-4ada-920a-ba9cf5b442de.download size: 0 2022-12-20 18:13:24.257 5ms deal execution in progress 2022-12-20 18:13:24.263 6ms deal queued for transfer transfer client id: 5,354,860 2022-12-20 18:13:52.006 28s start deal data transfer transfer client id: 5,354,860 2022-12-20 18:13:52.008 2ms http-transport: execute transfer deal size: 9,438,568,839 output file: /market/.boost/incoming/33316998-f823-4ada-920a-ba9cf5b442de.download time before context deadline: 23h59m59.999992106s 2022-12-20 18:13:52.013 5ms http-transport: existing file size deal size: 9,438,568,839 file size: 0 2022-12-20 18:13:52.017 4ms http-transport: libp2p-http url multiaddr: /ip4/147.28.156.33/tcp/6745 peer id: 12D3KooWQt6XqZ5XMkUiSqnfekep2X5o1GQxxKkKWj2mg1QYBXco url: libp2p://12D3KooWQt6XqZ5XMkUiSqnfekep2X5o1GQxxKkKWj2mg1QYBXco 2022-12-20 18:13:52.020 3ms http-transport: started async http transfer 2022-12-20 18:13:52.021 1ms http-transport: sending http request range-rq: bytes=0- received: 0 remaining: 9,438,568,839 2022-12-20 18:13:53.838 2s
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:13:53.845 7ms http-transport: some data was transferred before connection error, so resetting backoff to zero transferred: 61 2022-12-20 18:13:53.847 2ms http-transport: backing off before retrying http request attempts: 1 backoff time: 5s 2022-12-20 18:13:58.848 5s
http-transport: back-off complete, retrying http request backoff time: 5s 2022-12-20 18:13:58.855 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:13:59.524 669ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:13:59.530 6ms http-transport: backing off before retrying http request attempts: 2 backoff time: 7.233891231s 2022-12-20 18:14:06.764 7s
http-transport: back-off complete, retrying http request backoff time: 7.233891231s 2022-12-20 18:14:06.771 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:14:07.438 667ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:14:07.445 7ms http-transport: backing off before retrying http request attempts: 3 backoff time: 7.147743309s 2022-12-20 18:14:14.593 7s
http-transport: back-off complete, retrying http request backoff time: 7.147743309s 2022-12-20 18:14:14.600 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:14:15.267 667ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:14:15.274 7ms http-transport: backing off before retrying http request attempts: 4 backoff time: 10.766629557s 2022-12-20 18:14:26.041 11s http-transport: back-off complete, retrying http request backoff time: 10.766629557s 2022-12-20 18:14:26.048 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:14:26.716 668ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:14:26.723 7ms http-transport: backing off before retrying http request attempts: 5 backoff time: 15.716676867s 2022-12-20 18:14:42.440 16s http-transport: back-off complete, retrying http request backoff time: 15.716676867s 2022-12-20 18:14:42.447 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:14:43.115 668ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:14:43.121 6ms http-transport: backing off before retrying http request attempts: 6 backoff time: 21.43935264s 2022-12-20 18:15:04.561 21s http-transport: back-off complete, retrying http request backoff time: 21.43935264s 2022-12-20 18:15:04.567 6ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:15:15.824 11s http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:15:15.830 6ms http-transport: backing off before retrying http request attempts: 7 backoff time: 5.772288846s 2022-12-20 18:15:21.603 6s
http-transport: back-off complete, retrying http request backoff time: 5.772288846s 2022-12-20 18:15:21.610 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:15:22.277 667ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:15:22.284 7ms http-transport: backing off before retrying http request attempts: 8 backoff time: 1m15.717418996s 2022-12-20 18:16:38.001 1m
http-transport: back-off complete, retrying http request backoff time: 1m15.717418996s 2022-12-20 18:16:38.008 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:16:51.952 14s http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:16:51.956 4ms http-transport: backing off before retrying http request attempts: 9 backoff time: 1m52.829222054s 2022-12-20 18:18:44.785 2m
http-transport: back-off complete, retrying http request backoff time: 1m52.829222054s 2022-12-20 18:18:44.792 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:18:45.928 1s
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:18:45.932 4ms http-transport: backing off before retrying http request attempts: 10 backoff time: 1m38.411623696s 2022-12-20 18:20:24.344 2m
http-transport: back-off complete, retrying http request backoff time: 1m38.411623696s 2022-12-20 18:20:24.352 8ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:20:25.027 675ms
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:20:25.034 7ms http-transport: backing off before retrying http request attempts: 11 backoff time: 4m47.704750756s 2022-12-20 18:25:12.739 5m
http-transport: back-off complete, retrying http request backoff time: 4m47.704750756s 2022-12-20 18:25:12.746 7ms http-transport: sending http request range-rq: bytes=61- received: 61 remaining: 9,438,568,778 2022-12-20 18:25:13.871 1s
http-transport: http request error http code: 0 outputErr: error reading from http response stream: unexpected EOF 2022-12-20 18:25:13.878 7ms http-transport: backing off before retrying http request attempts: 12 backoff time: 3m33.781663682s