Sharepoint backup abruptly dies: WSACancelBlockingCall

How is your TMP Share setup? i.e is it a drive connected internally or externally to the sharepoint server?

Hi  Chris,

The TMP share is  located in D:\backupdir\

Actually the sharepoint  services is a single server configuration where everything is  local.

Server version:  Sharepoint services 3.0 SP2, SQL 2005

Hi Simon,

Thanks for the useful info. I found the log file with the following error messages:

2011-02-02 10:39:19  avmoss Error <9022>: MOSS backup: Hodnota je mimo oÄŤekávanĂ˝  rozsah.

2011-02-02 10:39:19  avmoss Error <9022>: MOSS backup:    v  Microsoft.SharePoint.Administration.Backup.SPBackupSettings.set_BackupThreads(Int32  value)

   v  MOSSWrapper.Backup(MOSSWrapper* , UInt16* pComponent, UInt16* pBackupDir,  UInt16* pType, Int32 threadsCount)

2011/02/02-09:39:19.17700  [avmoss_assist]  <=== avmoss::Backup

2011/02/02-09:39:19.17700  [avmoss_assist]  ===> avmoss::~avmoss

2011/02/02-09:39:19.19300  [avmoss_assist]  <=== avmoss::~avmoss

2011-02-02 10:39:19  avmoss Error <9437>: AssistOperation failed.  Quitting.

We tried stsadm for Sharepoint backup and it worked, we also tried Central Administration console and it also worked. Avamar backup is still showing up an error.

Alfredo

Atatched below the complete log file

*********** C:\Program Files\avs\var\MOD-1296639302106-3017-moss.log ***********
--------------------------------------------------------------------------------------------------------
----- START avmoss log 2011-02-02 10:39:16 Střední Evropa (běžný čas)  [5.0.106-28 Windows Server 2003 Server Terminal Services SP 2.0-x86]
--------------------------------------------------------------------------------------------------------

2011-02-02 10:39:16 avmoss Info <5241>: Logging to C:\Program Files\avs\var\MOD-1296639302106-3017-moss.log
2011-02-02 10:39:16 avmoss Info <8240>: Directory '\\?\C:\Program Files\avs\var\MOSS' already exists.
2011-02-02 10:39:16 avmoss Info <6636>: CTL listening on port 2895
2011/02/02-09:39:19.09899 [avmoss_assist]  workorder::parsexmlflags 'workorder free-form'
2011/02/02-09:39:19.09899 [avmoss_assist]  workorder::parsexmlflags null message for 'workorder directives'
2011-02-02 10:39:19 avmoss Info <7025>: Targets count 1
2011-02-02 10:39:19 avmoss Info <7026>:     target[0] = 'Farma/SharedServices/'
2011/02/02-09:39:19.09899 [avmoss_assist]  ===> avmoss::IsStandalone
2011-02-02 10:39:19 avmoss Info <9425>: MOSS deployment: Farm.
2011/02/02-09:39:19.09899 [avmoss_assist]  ===> avmoss_assist::DoBackupPrerequisites
2011-02-02 10:39:19 avmoss Info <9420>: Backup type: full.
2011/02/02-09:39:19.09899 [avmoss_assist]  ===> avmoss_assist::GetSubTargets
2011/02/02-09:39:19.09899 [avmoss_assist]  guiTarget: Farma/SharedServices
2011/02/02-09:39:19.09899 [avmoss_assist]  <=== avmoss_assist::GetSubTargets
2011/02/02-09:39:19.09899 [avmoss_assist]  ===> avmoss::IsDiskSpaceAvailable
2011/02/02-09:39:19.11500 [avmoss_assist]  <=== avmoss::IsDiskSpaceAvailable
2011/02/02-09:39:19.11500 [avmoss_assist]  <=== avmoss_assist::DoBackupPrerequisites
2011-02-02 10:39:19 avmoss Info <9430>: Copy 'C:\Program Files\avs\var\spbrtoc.xml' to 'D:\backupdir\spbrtoc.xml'.
2011/02/02-09:39:19.13100 [avmoss_assist]  Contents file copied from '\\?\C:\Program Files\avs\var\spbrtoc.xml' to '\\?\D:\backupdir\spbrtoc.xml'.
2011-02-02 10:39:19 avmoss Info <7028>: Working on target 'Farma/SharedServices'
2011/02/02-09:39:19.13100 [avmoss_assist]  Searching for target in the list of backup components.
2011/02/02-09:39:19.13100 [avmoss_assist]  Target 'Farma/SharedServices' found.
2011/02/02-09:39:19.13100 [avmoss_assist]  Number of subtargets: 0
2011-02-02 10:39:19 avmoss Info <9431>: Number of threads that will be used in backup process: '0'.
2011/02/02-09:39:19.13100 [avmoss_assist]  Local backup path: D:\backupdir\MOD-1296639302106#1
2011-02-02 10:39:19 avmoss Info <9432>: Creating local backup directory 'D:\backupdir\MOD-1296639302106#1'
2011-02-02 10:39:19 avmoss Info <8243>: Directory \\?\D:\backupdir\MOD-1296639302106#1 created.
2011/02/02-09:39:19.13100 [avmoss_assist]  Contents file copied from '\\?\D:\backupdir\spbrtoc.xml' to '\\?\D:\backupdir\MOD-1296639302106#1\spbrtoc.xml'.
2011/02/02-09:39:19.13100 [avmoss_assist_logging]  Startup, pid=752 tid=38 stack=0927FE5F td=00C9C874
2011-02-02 10:39:19 avmoss Info <8211>: Entered avmoss_assist_logging::body()
2011/02/02-09:39:19.13100 [avmoss_assist]  ===> avmoss::avmoss
2011/02/02-09:39:19.13100 [avmoss_assist]  ===> avmoss::InitDll
2011/02/02-09:39:19.13100 [avmoss_assist]  <=== avmoss::InitDll
2011/02/02-09:39:19.13100 [avmoss_assist]  <=== avmoss::avmoss
2011/02/02-09:39:19.13100 [avmoss_assist]  ===> avmoss::Backup
2011-02-02 10:39:19 avmoss Error <9022>: MOSS backup: Hodnota je mimo očekávaný rozsah.
2011-02-02 10:39:19 avmoss Error <9022>: MOSS backup:    v Microsoft.SharePoint.Administration.Backup.SPBackupSettings.set_BackupThreads(Int32 value)

   v MOSSWrapper.Backup(MOSSWrapper* , UInt16* pComponent, UInt16* pBackupDir, UInt16* pType, Int32 threadsCount)
2011/02/02-09:39:19.17700 [avmoss_assist]  <=== avmoss::Backup
2011/02/02-09:39:19.17700 [avmoss_assist]  ===> avmoss::~avmoss
2011/02/02-09:39:19.19300 [avmoss_assist]  <=== avmoss::~avmoss
2011-02-02 10:39:19 avmoss Error <9437>: AssistOperation failed. Quitting.
2011-02-02 10:39:21 avmoss Warning <0000>: File or directory D:\backupdir\MOD-1296639302106#1\spbr0000\spbackup.log not found.
2011-02-02 10:39:21 avmoss Info <9419>: Will check for the file every 2 seconds.
2011/02/02-09:39:21.19300 [avmoss_assist]  avmoss_assist::terminate() set terminated!
2011/02/02-09:39:21.19300 [avmoss_assist]  avmoss_assist::body() Done with sub-workorder invocation
2011/02/02-09:39:21.19300 [avmoss_assist]  Exiting avmoss_assist::body()
2011/02/02-09:39:21.19300 [avmoss_assist]  ===> avmoss::~avmoss
2011/02/02-09:39:21.19300 [avmoss_assist]  <=== avmoss::~avmoss
2011/02/02-09:39:21.19300 [avmoss_assist]  Thread finished avmoss_assist
2011/02/02-09:39:21.19300 [avmoss_ctl_sup]  sum_wrapups() 0 wrapups, 0 wrapup keys, snapview_wid MOD-1296639302106#0
2011/02/02-09:39:21.19300 [avmoss_ctl_sup]  xml_attr_add(): xml attribute errors not previously defined
2011/02/02-09:39:21.19300 [avmoss_ctl_sup]  xml_attr_add(): xml attribute warnings not previously defined
2011/02/02-09:39:21.19300 [avmoss_ctl_sup]  xml_attr_add(): xml attribute fatals not previously defined
2011-02-02 10:39:21 avmoss Info <7232>: Final summary generated subwork 1, cancelled/aborted 0, snapview 0, exitcode 536870919
2011/02/02-09:39:21.19300 [ctl_conn_send-3017-moss-MOD-1296639302106]  ctl_connection::lowsend:
send:
 

2011/02/02-09:39:21.19300 [ctl_conn_send-3017-moss-MOD-1296639302106]  ctl_connection::lowsend:
send:

2011-02-02 10:39:23 avmoss Warning <8213>: MOSS log not included to log file! File D:\backupdir\MOD-1296639302106#1\spbr0000\spbackup.log does not exist.
2011-02-02 10:39:23 avmoss Info <8214>: Exiting avmoss_assist_logging::body()
2011/02/02-09:39:23.13100 [avmoss_assist_logging]  Thread finished avmoss_assist_logging
2011/02/02-09:39:26.19300 [avmoss_ctl_sup]  ctl_manbase::process_message() done
2011/02/02-09:39:26.19300 [avmoss_ctl_sup]  Thread finished avmoss_ctl_sup
2011/02/02-09:39:26.19300 [ctl_conn_send-3017-moss-MOD-1296639302106]  Thread finished ctl_conn_send-3017-moss-MOD-1296639302106
2011/02/02-09:39:26.19300 [ctl_conn_recv-3017-moss-MOD-1296639302106]  tcpsockimpl::rawrecv2, got -1, but errno=0 (No error)  Error info code 10053: Software v hostitelském počítači ukončil vytvořené připojení
2011/02/02-09:39:26.19300 [ctl_conn_recv-3017-moss-MOD-1296639302106]  Recv socket is nul/closed
2011/02/02-09:39:26.19300 [ctl_conn_recv-3017-moss-MOD-1296639302106]  Thread finished ctl_conn_recv-3017-moss-MOD-1296639302106
2011/02/02-09:39:26.19300 [ctl_callhome]  Thread finished ctl_callhome
2011/02/02-09:39:26.19300 [avmoss]  back from callhome object terminate
2011/02/02-09:39:26.19300 [ctl_listen]  tcpsockimpl::genAccept code 10004: Blokovací operace byla přerušena voláním WSACancelBlockingCall
2011/02/02-09:39:26.19300 [ctl_listen]  Thread finished ctl_listen
2011/02/02-09:39:26.19300 [avmoss]  avmoss_app::run() returning 536870919
2011/02/02-09:39:26.19300 [avmoss]  avmoss: done 536870919
2011-02-02 10:39:26 avmoss Info <8248>: avmoss returning with exitcode 536870919
2011/02/02-09:39:26.19300 [avmoss]  avmoss_app::~avmoss_app
2011/02/02-09:39:26.19300 [avmoss]  Debug output end

--------------------------------------------------------------------------------------------------------
----- END avmoss log 2011-02-02 10:39:26 Střední Evropa (běžný čas)  (2 warnings, 3 errors, 0 fatal errors)
--------------------------------------------------------------------------------------------------------

*********** C:\Program Files\avs\var\MOD-1296639302106-3017-moss.alg ***********
--------------------------------------------------------------------------------------------------------
----- START MOD-1296639302106 log 2011-02-02 10:39:16 Střední Evropa (běžný čas)  [5.0.106-28 Windows Server 2003 Server Terminal Services SP 2.0-x86]
--------------------------------------------------------------------------------------------------------

2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: Workorder : MOD-1296639302106
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: Plugin ID : 3017-moss
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: bindir    : C:\Program Files\avs\bin
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: vardir    : C:\Program Files\avs\var
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: Executable : C:\Program Files\avs\bin\avmoss
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: Args       : avmoss --sysdir="C:\Program Files\avs\etc" --bindir="C:\Program Files\avs\bin" --vardir="C:\Program Files\avs\var" --ctlcallport="2244" --ctlinterface="3017-moss-MOD-1296639302106" --logfile="C:\Program Files\avs\var\MOD-1296639302106-3017-moss.log"
2011-02-02 10:39:16 MOD-1296639302106 Info <0000>: Order      :

  Farma/SharedServices/

 
   
   
   
   
   
   
   
   
   
 
 
   
   
   
   
 
 
 
   
 

2011-02-02 10:39:27 MOD-1296639302106 Warning <5598>: Workorder "MOD-1296639302106" non-zero exit status 'code 536870919: Miscellaneous error'
2011-02-02 10:39:27 MOD-1296639302106 Info <0000>:

Simon,

This is very useful information, thank you. I'll try this out and give some feedback to the forum on Monday.

Thanks

We implemented the hotfix as suggested by Silas and so far so good, backups are working!