Issue Report
Environment
- Linux Mint 21.03
- Snapshot build #344
Problem
Running Snapshot Build #344, farmer and plotter ran fine for around 12 hours, but then this happened in the farmer log:
2024-06-10T01:08:55.475342Z INFO {farm_index=6}:{sector_index=3581}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.64% complete)
2024-06-10T01:09:15.322013Z INFO {farm_index=16}: subspace_farmer::reward_signing: Successfully signed reward hash 0x48690ec9bae25b2a401e2714401bdfec9374cf262902003213404943ae0c3a09
thread 'thread 'thread 'thread '<unnamed>thread '<unnamed><unnamed>thread 'thread 'thread 'thread '<unnamed>' panicked at thread 'thread '' panicked at thread 'thread 'thread 'thread '<unnamed><unnamed>' panicked at ' panicked at thread '<unnamed>thread '<unnamed>thread 'thread '<unnamed>thread 'thread '<unnamed>/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rsthread '<unnamed>thread '<unnamed>/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rsthread 'thread 'thread '<unnamed><unnamed><unnamed>' panicked at ' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs<unnamed>' panicked at <unnamed>' panicked at <unnamed><unnamed>' panicked at <unnamed><unnamed>' panicked at :<unnamed>' panicked at <unnamed>' panicked at :<unnamed><unnamed><unnamed>' panicked at ' panicked at ' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs::' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs' panicked at ' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs' panicked at ' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs222' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs222' panicked at ' panicked at ' panicked at /home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs::222222/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs:/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs:/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs:/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs::/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs:/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs::/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs/home/ubuntu/actions-runner/_work/subspace/subspace/crates/subspace-farmer-components/src/reading.rs:::222222:::222:222::222::22230:222:22230:::222222222::3030222:222:222222:222222::
222:222::
222222222:::3030:
:
:30:30::30::30encoded_chunk_used implies proof exists for this chunk; qed:30:30encoded_chunk_used implies proof exists for this chunk; qed:::303030:
:
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed30:
30:
3030:
3030:
30:
30:
303030:
:
:
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed
:
encoded_chunk_used implies proof exists for this chunk; qed:
encoded_chunk_used implies proof exists for this chunk; qed:
:
encoded_chunk_used implies proof exists for this chunk; qed:
:
encoded_chunk_used implies proof exists for this chunk; qednote: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
:
encoded_chunk_used implies proof exists for this chunk; qed:
encoded_chunk_used implies proof exists for this chunk; qed:
:
:
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qed
encoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qedencoded_chunk_used implies proof exists for this chunk; qed
This repeats several times, interspersed with messages showing many, but not every, farm errored and stopped.
2024-06-10T01:13:00.873236Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:13:02.360908Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:13:02.587607Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:13:11.337165Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:13:12.132915Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:13:30.875186Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:13:32.362616Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:13:32.588622Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:13:41.338968Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:13:42.133655Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:14:00.876219Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:14:02.363602Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:14:02.589822Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:14:11.340271Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:14:12.134495Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:14:30.877707Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:14:32.364965Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:14:32.590260Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:14:41.341829Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:14:42.135935Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:15:00.878207Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:15:02.366533Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:15:02.591602Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:15:07.534845Z INFO {farm_index=0}: subspace_farmer::reward_signing: Successfully signed reward hash 0x72dcaaf8c39bc85b7abc6f61f797566cba934f0b2da3d65856e112d0a64f148c
2024-06-10T01:15:11.342923Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:15:12.137914Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:15:30.879886Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:15:32.367286Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:15:32.592291Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:15:41.343124Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:15:42.139011Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:16:00.881574Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:16:02.368494Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:16:02.593476Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:16:11.344520Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:16:12.140110Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
2024-06-10T01:16:30.882631Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=11 error=Background task reading-11 panicked
2024-06-10T01:16:32.370140Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=19 error=Background task reading-19 panicked
2024-06-10T01:16:32.594172Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=8 error=Background task reading-8 panicked
2024-06-10T01:16:41.345086Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=20 error=Background task reading-20 panicked
2024-06-10T01:16:42.141801Z ERROR subspace_farmer::commands::cluster::farmer: Farm errored and stopped farm_index=2 error=Background task reading-2 panicked
Note that at this point I was not farming any remote plots. This was on a threadripper with 512GB RAM farming 9 local 15.36TB U.2 drives. For various unimportant reasons I was not attempting to connect remote farms to it. So LAN bandwidth issues wouldn’t be a cause.
I stopped and restart the farm and plotter. Farming seems to be okay, but the plotting will not resume normal operation. Normally this computer would farm 4 plots in 8 minutes under its current load and --plotting-thread-pool-size set to 8, to only use 50% CPU at the moment. Here you see it taking over an hour and not properly finishing more than one set of sectors.
2024-06-10T01:36:47.697203Z INFO {farm_index=28}: subspace_farmer::reward_signing: Subscribing to reward signing notifications
2024-06-10T01:37:15.972999Z INFO {farm_index=22}:{sector_index=3584}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.72% complete)
2024-06-10T01:37:15.977241Z INFO {farm_index=22}:{sector_index=3585}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.75% complete)
2024-06-10T01:37:15.980352Z INFO {farm_index=22}:{sector_index=3586}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.78% complete)
2024-06-10T01:37:15.981108Z INFO {farm_index=22}:{sector_index=3587}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.81% complete)
2024-06-10T01:37:15.981629Z INFO {farm_index=22}:{sector_index=3588}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.83% complete)
2024-06-10T01:57:02.628793Z INFO {farm_index=16}: subspace_farmer::reward_signing: Successfully signed reward hash 0xc436c1afc00d599bfe4af572d535d7699ac9cca4f3a9e92dacde8856c1d98456
2024-06-10T01:57:57.610233Z INFO {farm_index=21}: subspace_farmer::reward_signing: Successfully signed reward hash 0xf3216d3c74771a712539bac14083e3f832c80ef317e583502b49ea75e435d449
2024-06-10T02:00:00.660483Z INFO {farm_index=27}: subspace_farmer::reward_signing: Successfully signed reward hash 0xde585463a4ac78b7e45216f4da25dd55ecd983b8d32f90ce1e13ad14c2017993
2024-06-10T02:02:03.689117Z INFO {farm_index=18}:{sector_index=3576}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.50% complete)
2024-06-10T02:02:03.693960Z INFO {farm_index=18}:{sector_index=3577}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.53% complete)
2024-06-10T02:02:03.702234Z INFO {farm_index=18}:{sector_index=3578}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.55% complete)
2024-06-10T02:02:03.710421Z INFO {farm_index=18}:{sector_index=3579}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.58% complete)
2024-06-10T02:02:03.718307Z INFO {farm_index=18}:{sector_index=3580}: subspace_farmer::single_disk_farm::plotting: Plotting sector (99.61% complete)
2024-06-10T02:02:25.979595Z WARN {farm_index=22}:{sector_index=3585}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:02:25.982758Z WARN {farm_index=22}:{sector_index=3586}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:02:25.983918Z WARN {farm_index=22}:{sector_index=3587}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:02:25.983969Z WARN {farm_index=22}:{sector_index=3588}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:02:25.988062Z WARN {farm_index=22}:{sector_index=3584}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:05:42.695127Z INFO {farm_index=2}: subspace_farmer::reward_signing: Successfully signed reward hash 0x1301b2cb6ff523c4f548462429e597f67ca2ed3874dcf9d1e28ab07cfa686ccb
2024-06-10T02:09:29.654573Z INFO {farm_index=25}: subspace_farmer::reward_signing: Successfully signed reward hash 0xf1bbf395a4687fd07c862a54a9fbd786888f062d61e8a1f12ccb98e0d98d3e26
2024-06-10T02:09:34.757776Z INFO {farm_index=24}: subspace_farmer::reward_signing: Successfully signed reward hash 0x734ba618aedacdceeda487eb1551ed041a947efaa58b1c5c781c536674139ba1
2024-06-10T02:26:06.780974Z INFO {farm_index=2}: subspace_farmer::reward_signing: Successfully signed reward hash 0xbf7a4c43519e37340c9f3c035928b2ed4e870156484db72276b908190a95843c
2024-06-10T02:35:53.399883Z WARN {farm_index=18}:{sector_index=3580}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:35:53.400678Z WARN {farm_index=18}:{sector_index=3578}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:35:53.400715Z WARN {farm_index=18}:{sector_index=3576}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:35:53.400725Z WARN {farm_index=18}:{sector_index=3579}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
2024-06-10T02:35:53.400733Z WARN {farm_index=18}:{sector_index=3577}: subspace_farmer::single_disk_farm::plotting: Failed to plot sector, retrying in 1s error=Low-level plotting error: Timed out without ping from plotter
(The last set of Failed to Plot Sector messages there were due to me control-c’ing the plotter, since it clearly wasn’t doing enough to justify continuing to run it at this point in time.)
This is the most detail I can provide at the moment, many distractions atm, sorry.