Farmer benchmark prove error

[keens312]

Issue Report

On Ubuntu 18.04 aarch64, prove failed

Environment

• Operating System: Ubuntu 18.04 arm64
• Pulsar/Advanced CLI/Docker: CLI

Problem

Farming works fine, but prove failed.
The folder “/data/localfarm/” is local disk.

[Paste any errors or relevant logs here]
root@MC059FDB17003567:~/subspace# RUST_BACKTRACE=1 ./subspace-farmer benchmark prove /data/localfarm/
Benchmarking prove/plot/rayon/regular/concurrent-chunks
Benchmarking prove/plot/rayon/regular/concurrent-chunks: Warming up for 3.0000 s
thread ‘main’ panicked at crates/subspace-farmer/src/bin/subspace-farmer/commands/benchmark.rs:473:45:
called Option::unwrap() on a None value
stack backtrace:
0: rust_begin_unwind
1: core::panicking::panic_fmt
2: core::panicking::panic
3: core::option::unwrap_failed
4: criterion::bencher::Bencher::iter_batched
5: <criterion::routine::Function<M,F,T> as criterion::routine::Routine<M,T>>::warm_up
6: criterion::routine::Routine::sample
7: criterion::analysis::common
8: criterion::benchmark_group::BenchmarkGroup::bench_function
9: subspace_farmer::commands::benchmark::benchmark
10: subspace_farmer::main
note: Some details are omitted, run with RUST_BACKTRACE=full for a verbose backtrace.

[nazar-pc]

Are you sure you have anything plotted there? Because if you have nothing plotted then there is nothing that can be proven either.

[keens312]

Yes, plotting is done, farmer is running correctly but prove test still failed.

drwxrwxr-x 2 qjyu qjyu 4096 Apr 17 20:00 ./
drwxrwxrwx 9 root root 4096 Apr 17 19:04 …/
-rw-rw-r-- 1 qjyu qjyu 33 Apr 17 19:05 identity.bin
-rw-rw-r-- 1 qjyu qjyu 961284 Apr 17 22:40 known_addresses.bin
-rw-rw-r-- 1 qjyu qjyu 1314816 Apr 17 22:35 metadata.bin
-rw-rw-r-- 1 qjyu qjyu 29364224 Apr 17 22:41 piece_cache.bin
-rw-rw-r-- 1 qjyu qjyu 2113794048 Apr 17 22:53 plot.bin
-rw-rw-r-- 1 qjyu qjyu 252 Apr 17 22:35 single_disk_farm.json

./target/debug/subspace-farmer farm --node-rpc-url ws://localhost:9944 --listen-on /ip4/0.0.0.0/tcp/30533 --listen-on /ip6/::/tcp/30533 --listen-on /ip4/0.0.0.0/udp/30533/quic-v1 --listen-on /ip6/::/udp/30533/quic-v1 --in-connections 300 --out-connections 100 --pending-in-connections 100 --pending-out-connections 100 --allow-private-ips --sector-encoding-concurrency 3 --sector-downloading-concurrency 3 --reward-address st9K5vay1b8Jct6M6Jh9dzNUcLFz8NitmWEXyEBncYttVUTgh path=/data/farm/,size=1GiB
2024-04-17T14:35:26.655644Z INFO subspace_farmer::commands::farm: Connecting to node RPC url=ws://localhost:9944
2024-04-17T14:35:26.722588Z INFO subspace_networking: DSN instance configured. allow_non_global_addresses_in_dht=true peer_id=12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH protocol_version=/subspace/2/0c121c75f4ef450f40619e1fca9d1e8e7fbabc42c895bc4790801e85d5a91c34
2024-04-17T14:35:26.727887Z INFO libp2p_swarm: local_peer_id=12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
thread ‘main’ panicked at crates/subspace-farmer/src/utils.rs:418:18:
chunk size must be non-zero
note: run with RUST_BACKTRACE=1 environment variable to display a backtrace
qjyu@parallels-Parallels-Virtual-Platform:/data/subspace$ vim start_farm.sh
qjyu@parallels-Parallels-Virtual-Platform:/data/subspace$ ./start_farm.sh
2024-04-17T14:35:46.449733Z INFO subspace_farmer::commands::farm: Connecting to node RPC url=ws://localhost:9944
2024-04-17T14:35:46.532136Z INFO subspace_networking: DSN instance configured. allow_non_global_addresses_in_dht=false peer_id=12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH protocol_version=/subspace/2/0c121c75f4ef450f40619e1fca9d1e8e7fbabc42c895bc4790801e85d5a91c34
2024-04-17T14:35:46.539363Z INFO libp2p_swarm: local_peer_id=12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:35:47.261680Z INFO subspace_farmer::commands::farm: Preparing plotting thread pools plotting_thread_pool_core_indices=[CpuCoreSet { cores: CpuSet(0-1), … }] replotting_thread_pool_core_indices=[CpuCoreSet { cores: CpuSet(0), … }]
2024-04-17T14:35:47.331940Z INFO {farm_index=0}: subspace_farmer::single_disk_farm: Farm size has changed old_space=1.0 GiB new_space=2.0 GiB
2024-04-17T14:35:47.334954Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::plot_cache: Checking plot cache contents, this can take a while
2024-04-17T14:35:47.360391Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::plot_cache: Finished checking plot cache contents
2024-04-17T14:35:47.363644Z INFO {farm_index=0}: subspace_farmer::single_disk_farm: Benchmarking faster proving method
2024-04-17T14:38:03.091581Z INFO {farm_index=0}: subspace_farmer::single_disk_farm: Faster proving method found fastest_mode=WholeSector
2024-04-17T14:38:03.131623Z INFO {farm_index=0}: subspace_farmer::commands::farm: Farm 0:
2024-04-17T14:38:03.131691Z INFO {farm_index=0}: subspace_farmer::commands::farm: ID: 01HVNYWGGKJ1GPBEX635EX56HA
2024-04-17T14:38:03.136493Z INFO {farm_index=0}: subspace_farmer::commands::farm: Genesis hash: 0x0c121c75f4ef450f40619e1fca9d1e8e7fbabc42c895bc4790801e85d5a91c34
2024-04-17T14:38:03.136589Z INFO {farm_index=0}: subspace_farmer::commands::farm: Public key: 0xcccdb486e346a6bcda76ae0e5f70c41ded3d1848df3eb83985b1c18ce22ebf05
2024-04-17T14:38:03.138720Z INFO {farm_index=0}: subspace_farmer::commands::farm: Allocated space: 2.0 GiB (2.1 GB)
2024-04-17T14:38:03.138772Z INFO {farm_index=0}: subspace_farmer::commands::farm: Directory: /data/farm/
2024-04-17T14:38:03.146633Z INFO subspace_farmer::commands::farm: Collecting already plotted pieces (this will take some time)…
2024-04-17T14:38:03.154551Z INFO subspace_farmer::farmer_cache: Initializing piece cache
2024-04-17T14:38:03.154869Z INFO subspace_farmer::commands::farm: Finished collecting already plotted pieces successfully
2024-04-17T14:38:03.206128Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::farming: Subscribing to slot info notifications
2024-04-17T14:38:03.206959Z INFO {farm_index=0}: subspace_farmer::reward_signing: Subscribing to reward signing notifications
2024-04-17T14:38:03.226917Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip4/127.0.0.1/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.248549Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::plotting: Subscribing to archived segments
2024-04-17T14:38:03.264068Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip6/::1/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.264308Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip4/10.211.55.17/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.264456Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip6/fdb2:2c26:f4e4:0:be37:8404:7553:ab10/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.265326Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip6/fdb2:2c26:f4e4:0:1dc1:f8b:da8b:5760/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.265731Z INFO subspace_farmer::commands::farm::dsn: DSN listening on /ip6/fe80::64e6:2b1a:ce3:6ab4/tcp/30533/p2p/12D3KooWNaaXzN3uzBuBJYnsC5aKpmm11dnkE6kFv4cvmF7cP3RH
2024-04-17T14:38:03.380407Z INFO subspace_farmer::farmer_cache: Synchronizing piece cache
2024-04-17T14:38:04.311235Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::plotting: Plotting sector (0.00% complete) sector_index=0
2024-04-17T14:41:14.500817Z INFO subspace_farmer::farmer_cache: Finished piece cache synchronization

[keens312]

node is still in syncing, is this the reason for benchmark proving failure?

[nazar-pc]

@keens312

2024-04-17T14:38:04.311235Z INFO {farm_index=0}: subspace_farmer::single_disk_farm::plotting: Plotting sector (0.00% complete) sector_index=0

This says nothing is actually plotted as originally predicted.