"No storage was registered" with 4.10.8 (works with 4.1.x)

[jmuchemb]

Hello Nigel, glad to see some recent development on TuxOnIce.

As already reported on the mailing list, TOI started to fail at version 4.2 with btrfs, and I'm still using the 4.1.x LTS branch because of that. I'd like to upgrade.

I've just tried 4.10.8 merged with tuxonice/tuxonice-4.10. I was surprised to see that TOI does not find any storage (UI disabled to begin). /dev/mapper/swap is encrypted.

[  716.287199] Adding 4194300k swap on /dev/mapper/swap.  Priority:32767 extents:1 across:4194300k SS
[  716.289515] Initiating a hibernation cycle.
[  716.294893] TuxOnIce: Userui program not configured.
[  716.296878] Starting other threads.
[  716.297457] Freezing processes & syncing filesystems.
[  716.301829] Freezing user space processes ... (elapsed 0.007 seconds) done.
[  716.310427] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  716.417944] Preparing Image. Try 1.
[  716.419131] Restarting kernel threads ... done.
[  716.420539] TuxOnIce: No storage was registered.
[  716.421718] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  716.521158] Preparing Image. Try 2.
[  716.522369] Restarting kernel threads ... done.
[  716.523770] TuxOnIce: No storage was registered.
[  716.524984] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  716.624528] Free:834644(834644). Sets:53433(53433),130351(130351). Nosave:23491-0=23491. Storage:0/1045474(92142=>91892). Needed:0,0,0(100,1827,500,0) (PS2:no)
[  716.625772] Failed to prepare the image because...
[  716.627021] - The maximum number of iterations was reached without successfully preparing the image.
[  716.628306] Unable to successfully prepare the image.
[  716.628306] 
[  716.630828] Cleaning up...
[  716.632098] Restarting tasks ... done.
[  716.649347] video LNXVIDEO:00: Restoring backlight state
[  716.650094] TuxOnIce debugging info:
[  716.650094] - TuxOnIce core  : 3.3
[  716.650094] - Kernel Version : 4.10.8+
[  716.650094] - Compiler vers. : 4.9
[  716.650094] - Attempt number : 2
[  716.650094] - Parameters     : 4097 536584 0 1 0 0
[  716.650094] - Overall expected compression percentage: 50.
[  716.650094] - Checksum method is 'md4'.
[  716.650094]   0 pages resaved in atomic copy.
[  716.650094] - Compressor is 'lz4'.
[  716.650094] - Block I/O active.
[  716.650094] - Max outstanding reads 1. Max writes 0.
[  716.650094]   Memory_needed: 1024 x (4096 + 376 + 136) = 4718592 bytes.
[  716.650094]   Free mem throttle point reached 0.
[  716.650094] - Swap Allocator enabled.
[  716.650094]   Attempting to automatically swapon: /dev/mapper/swap.
[  716.650094]   Swap available for image: 1048575 pages.
[  716.650094] - File Allocator active.
[  716.650094]   Storage available for image: 0 pages.

Here are logs when it works (4.1.39):

[21165.053712] Adding 4194300k swap on /dev/mapper/swap.  Priority:32767 extents:1 across:4194300k SS
[21165.053728] Initiating a hibernation cycle.
[21165.298801] Console is 112x200.
[21165.298883] Using configuration file /etc/splash/tuxonice/1600x900.cfg.
[21165.365519] Framebuffer support initialised successfully.
[21165.371604] Starting other threads.
[21165.371661] Freezing user space processes ... (elapsed 0.001 seconds) done.
[21165.372974] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[21165.518735] Restarting kernel threads ... done.
[21165.542040] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[21166.083530] ...20%...40%...60%...80%
...