Unable to restore users using Backup Plugin

[devin_mm 3]

I moved my server onto a new installation and when I try and restore from any of my three backups I just have the spinning circle and it never loads any users.

Is there something I can do to resolve this?

 

[Happy2Play 9446]

Sometimes it is browser related, but you will need to post the server log reflecting the restore process.

[devin_mm 3]

I have tried the restore process with 3 browsers now (Chrome, Firefox, Edge) with the same result.  I have also copied the backup off the network and onto my local machine with the same result.

 

Here is the log:

2019-08-12 19:00:22.201 Info HttpServer: HTTP GET http://192.168.1.66:8096/emby/Plugins/8abc6789-fde2-4705-8592-4028806fa343/Thumb?maxWidth=395&tag=1. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:22.202 Info HttpServer: HTTP Response 200 to 10.200.254.111. Time: 2ms. http://192.168.1.66:8096/emby/Plugins/8abc6789-fde2-4705-8592-4028806fa343/Thumb?maxWidth=395&tag=1
2019-08-12 19:00:23.393 Info HttpServer: HTTP GET http://192.168.1.66:8096/web/configurationpage?name=Emby%20Backup&v=4.2.1.0&r=0. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:23.393 Info HttpServer: HTTP Response 304 to 10.200.254.111. Time: 0ms. http://192.168.1.66:8096/web/configurationpage?name=Emby%20Backup&v=4.2.1.0&r=0
2019-08-12 19:00:23.502 Info HttpServer: HTTP GET http://192.168.1.66:8096/emby/Plugins/e711475e-efad-431b-8527-033ba9873a34/Configuration. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:23.503 Info HttpServer: HTTP Response 200 to 10.200.254.111. Time: 1ms. http://192.168.1.66:8096/emby/Plugins/e711475e-efad-431b-8527-033ba9873a34/Configuration
2019-08-12 19:00:23.574 Info HttpServer: HTTP GET http://192.168.1.66:8096/emby/MBBackup/Backups. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:23.603 Info HttpServer: HTTP Response 200 to 10.200.254.111. Time: 29ms. http://192.168.1.66:8096/emby/MBBackup/Backups
2019-08-12 19:00:25.462 Info HttpServer: HTTP GET http://192.168.1.66:8096/web/configurationpage?name=MBBackupRestore&path=T:%5CEmbyBack%5CEmby%20Backup%20-%202019-08-10%2002.52.51%20-%20Auto&v=4.2.1.0&r=0. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:25.463 Info HttpServer: HTTP Response 200 to 10.200.254.111. Time: 0ms. http://192.168.1.66:8096/web/configurationpage?name=MBBackupRestore&path=T:%5CEmbyBack%5CEmby%20Backup%20-%202019-08-10%2002.52.51%20-%20Auto&v=4.2.1.0&r=0
2019-08-12 19:00:25.564 Info HttpServer: HTTP GET http://192.168.1.66:8096/emby/MBBackup/Backups?Path=T%3A%5CEmbyBack%5CEmby%20Backup%20-%202019-08-10%2002.52.51%20-%20Auto. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36
2019-08-12 19:00:25.568 Info HttpServer: HTTP Response 200 to 10.200.254.111. Time: 4ms. http://192.168.1.66:8096/emby/MBBackup/Backups?Path=T%3A%5CEmbyBack%5CEmby%20Backup%20-%202019-08-10%2002.52.51%20-%20Auto
2019-08-12 19:00:32.604 Info HttpServer: HTTP GET http://localhost:8096/emby/System/Logs/embyserver.txt. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:68.0) Gecko/20100101 Firefox/68.0

To me there's nothing useful in it.

[devin_mm 3]

It's possible that it's because the original backups were done on a machine running Windows Server 2019 (baremetal) and I was trying to restore on a computer running Windows 10 Pro Workstation (same hardware, baremetal).

I say that because after I nuked the hard drive again and installed VMWare with a Windows Server 2019 VM all the users showed up instantly.

[Happy2Play 9446]

It's possible that it's because the original backups were done on a machine running Windows Server 2019 (baremetal) and I was trying to restore on a computer running Windows 10 Pro Workstation (same hardware, baremetal).

I say that because after I nuked the hard drive again and installed VMWare with a Windows Server 2019 VM all the users showed up instantly.

 

No from a users/userdata standpoint OS shouldn't even matter.

 

Can you post the full server log of a restore attempt as what you posted does not show the Restore process.

 

starting with this

2019-08-12 19:46:35.102 Info HttpServer: HTTP POST http://localhost:8096/emby/MBBackup/Profiles/Restore. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/76.0.3809.100 Safari/537.36

[Happy2Play 9446]

@@Luke this is only a test machine attempt but is did get this on a user restore. A in place system restore.

2019-08-12 19:46:35.187 Info Server Configuration Backup: Restored user Media
2019-08-12 19:46:35.251 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 4.2.1.0
	Command line: C:\Users\Media\Desktop\Stable\system\EmbyServer.dll -noautorunwebapp
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Runtime: file:///C:/Users/Media/Desktop/Stable/system/System.Private.CoreLib.dll
	Processor count: 4
	Program data path: C:\Users\Media\Desktop\Stable\programdata
	Application directory: C:\Users\Media\Desktop\Stable\system
	SQLitePCL.pretty.SQLiteException: Error: 12 values for 13 columns - replace into UserDatas (UserDataKeyId, key, userId, rating,played,playCount,isFavorite,playbackPositionTicks,LastPlayedDateInt,AudioStreamIndex,SubtitleStreamIndex,RatingLastModified,PlaystateLastModified) values (@UserDataKeyId, @userId, @rating,@played,@playCount,@isFavorite,@playbackPositionTicks,@LastPlayedDateInt,@AudioStreamIndex,@SubtitleStreamIndex,@RatingLastModified,@PlaystateLastModified) 
	SQLitePCL.pretty.SQLiteException: Exception of type 'SQLitePCL.pretty.SQLiteException' was thrown.
	   at SQLitePCL.pretty.SQLiteDatabaseConnection.PrepareStatement(ReadOnlySpan`1 sqlUtf8Bytes, String& tail)
	   at SQLitePCL.pretty.DatabaseConnection.PrepareStatement(IDatabaseConnection This, ReadOnlySpan`1 sqlUtf8)
	   at Emby.Server.Implementations.Data.SqliteUserDataRepository.SaveUserData(IDatabaseConnection db, Int64 internalUserId, String key, UserItemData userData)
	   at Emby.Server.Implementations.Data.SqliteUserDataRepository.SaveAllUserData(Int64 internalUserId, UserItemData[] userData, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Library.UserDataManager.SaveAllUserData(Int64 userId, UserItemData[] userData, CancellationToken cancellationToken)
	   at MBBackup.ServerEntryPoint.ExecuteRestore(BackupInfo settings, CancellationToken cancellationToken, IProgress`1 progress, Boolean isAuto)
	Source: SQLitePCL.pretty
	TargetSite: SQLitePCL.pretty.IStatement PrepareStatement(System.ReadOnlySpan`1[System.Byte], System.String ByRef)

Edited August 13, 2019 by Happy2Play

[Luke 40111]

 

@@Luke this is only a test machine attempt but is did get this on a user restore. A in place system restore.

2019-08-12 19:46:35.187 Info Server Configuration Backup: Restored user Media
2019-08-12 19:46:35.251 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 4.2.1.0
	Command line: C:\Users\Media\Desktop\Stable\system\EmbyServer.dll -noautorunwebapp
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Runtime: file:///C:/Users/Media/Desktop/Stable/system/System.Private.CoreLib.dll
	Processor count: 4
	Program data path: C:\Users\Media\Desktop\Stable\programdata
	Application directory: C:\Users\Media\Desktop\Stable\system
	SQLitePCL.pretty.SQLiteException: Error: 12 values for 13 columns - replace into UserDatas (UserDataKeyId, key, userId, rating,played,playCount,isFavorite,playbackPositionTicks,LastPlayedDateInt,AudioStreamIndex,SubtitleStreamIndex,RatingLastModified,PlaystateLastModified) values (@UserDataKeyId, @userId, @rating,@played,@playCount,@isFavorite,@playbackPositionTicks,@LastPlayedDateInt,@AudioStreamIndex,@SubtitleStreamIndex,@RatingLastModified,@PlaystateLastModified) 
	SQLitePCL.pretty.SQLiteException: Exception of type 'SQLitePCL.pretty.SQLiteException' was thrown.
	   at SQLitePCL.pretty.SQLiteDatabaseConnection.PrepareStatement(ReadOnlySpan`1 sqlUtf8Bytes, String& tail)
	   at SQLitePCL.pretty.DatabaseConnection.PrepareStatement(IDatabaseConnection This, ReadOnlySpan`1 sqlUtf8)
	   at Emby.Server.Implementations.Data.SqliteUserDataRepository.SaveUserData(IDatabaseConnection db, Int64 internalUserId, String key, UserItemData userData)
	   at Emby.Server.Implementations.Data.SqliteUserDataRepository.SaveAllUserData(Int64 internalUserId, UserItemData[] userData, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Library.UserDataManager.SaveAllUserData(Int64 userId, UserItemData[] userData, CancellationToken cancellationToken)
	   at MBBackup.ServerEntryPoint.ExecuteRestore(BackupInfo settings, CancellationToken cancellationToken, IProgress`1 progress, Boolean isAuto)
	Source: SQLitePCL.pretty
	TargetSite: SQLitePCL.pretty.IStatement PrepareStatement(System.ReadOnlySpan`1[System.Byte], System.String ByRef)

 

Yup, I see the issue there, thanks.

[Luke 40111]

So unfortunately that's an issue in the server. It will be resolved in the next beta release, and if we decide to do a 4.2.2 hotfix release, then it will be included in there. Otherwise it will be resolved in 4.3. Thanks.