Problem with backup to microsoft one drive


#1

Hello.

First sorry for my bad english, Im from El Salvador.
The error i have: ERROR: Destination error: [DST-6:1103#2] Failed to upload
I attached the log.

2018-03-10 12:05:45.4714|DEBUG|33|Pranas.Net.SqlBak.Service.SBF.Logging.FileMovedHandler|GrantAccess. Path for access: C:\ProgramData\Pranas.NET\SQLBackupAndFTP\AdvancedLog
2018-03-10 12:05:46.3956|TRACE|33|Pranas.Net.SqlBak.Service.SBF.Logging.BaseJoiningNlog|Active. Return.
2018-03-10 12:05:46.3956|DEBUG|33|Net.Pranas.SqlBak.Context.Context.Data.StateHandler|SaveState. Key: “Pranas.Net.SqlBak.Service.State.States.SbfLogState”
2018-03-10 12:05:46.5216|DEBUG|33|Net.Pranas.SqlBak.Context.Context.Data.StateHandler|SaveState. Return
2018-03-10 12:05:54.0867|DEBUG|33|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|RunJob. JobId: “1”
2018-03-10 12:05:54.0867|DEBUG|33|Pranas.Net.SqlBak.Service.Shell.AppLauncher|RunJob. Starting the job… JobId: “{LocalId:1; GlobalId:0}”; RunMode: “Manually”; BackupType: “Full”
2018-03-10 12:05:54.0867|DEBUG|33|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: “{LocalId:1; GlobalId:0}”
2018-03-10 12:05:54.0867|TRACE|33|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Reading global id… SqlText: “select [JobGlobalId] from [Job] where [JobId] = @JobId order by [JobVersion] desc limit 1”
2018-03-10 12:05:54.0867|TRACE|33|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. GlobalId: “0”
2018-03-10 12:05:54.0867|DEBUG|33|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: “{LocalId:1; GlobalId:0}”
2018-03-10 12:05:54.0867|DEBUG|33|Pranas.Net.SqlBak.Service.Context.ServiceProcess|FirstOrDefault. Enumerating processes…
2018-03-10 12:05:54.0867|TRACE|33|Pranas.Net.SqlBak.Service.Context.ServiceProcess|FirstOrDefault. Return ProcessId: “”
2018-03-10 12:05:54.0867|TRACE|52|Pranas.Net.SqlBak.Service.Shell.AppLauncher|RunCli. Running CLI process… ProcessId: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.0867|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.Program|Main. Starting CLI process…
2018-03-10 12:05:54.0867|TRACE|52|Net.Pranas.SqlBak.Job.Cli.Program|Main. CLI process id: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.0867|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.Program|Run. Cmd: “True”; ProcessId: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.0867|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.Program|Run. Running command: “-run”
2018-03-10 12:05:54.0867|TRACE|52|Net.Pranas.SqlBak.Job.Cli.Program|Run. Subscribing the process to SqlBak service… ProcessId: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.0867|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.ProgramCommon|GetChannelFactory. Getting the service channel factory…
2018-03-10 12:05:54.0867|TRACE|52|Net.Pranas.SqlBak.Job.Cli.ProgramCommon|GetChannelFactory. The current channel factory is null. Creating a new one…
2018-03-10 12:05:54.0867|DEBUG|33|Pranas.Net.SqlBak.Service.Shell.AppLauncher|RunJob. Done
2018-03-10 12:05:54.0867|DEBUG|33|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|RunJob. Return: “IsSuccess: True; Error: “”; Value: “True””
2018-03-10 12:05:54.0867|TRACE|33|Pranas.Net.SqlBak.Service.Shell.AppLauncher|RunJob. Updating the job status for desktop… JogId: “{LocalId:1; GlobalId:0}”; JobStatus: “Running”
2018-03-10 12:05:54.0997|DEBUG|51|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. Email list: informatica@pasteleriabanban.com
2018-03-10 12:05:54.0997|TRACE|51|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. Done.
2018-03-10 12:05:54.0997|TRACE|51|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. Invoke api.
2018-03-10 12:05:54.0997|DEBUG|51|Pranas.Net.SqlBak.Service.SBF.AgentIdentificator.SbfAgentKeyHandler|SbfApiInvoke
2018-03-10 12:05:54.0997|DEBUG|51|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Web.Client.SBFAccount.SbfAccountClient,Net.Pranas.SqlBak.Web.Client.SBFAccount.ValidationEmailResultInfo[]]" 2018-03-10 12:05:54.0997|TRACE|51|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.0997|TRACE|51|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.0997|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. Email list: 2018-03-10 12:05:54.0997|TRACE|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. Done. 2018-03-10 12:05:53.8837|DEBUG|1|Net.Pranas.SQLBackupAndFTP.Control.Job.BackupJobSettingsControl|RunJobOnClick 2018-03-10 12:05:53.9197|DEBUG|16|Net.Pranas.SBF.Context.Model.Common.AbstractCommand|CancelAsync 2018-03-10 12:05:53.9197|TRACE|16|Net.Pranas.SBF.Context.Model.Common.AbstractCommand|CancelAsync|System.ObjectDisposedException: CancellationTokenSource se ha desechado. en System.Threading.CancellationTokenSource.ThrowObjectDisposedException() en Net.Pranas.SBF.Context.Model.Common.AbstractCommand.CancelAsync(Boolean ignoreException) 2018-03-10 12:05:54.0857|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Commands.RunJobCommand|DoExecute. Parameter: "1" 2018-03-10 12:05:54.0987|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Common.Command.RequestValidationEmailCommand|DoExecute. Prameter: informatica@pasteleriabanban.com 2018-03-10 12:05:54.0987|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Common.Command.RequestValidationEmailCommand|DoExecute. Prameter: 2018-03-10 12:05:54.1987|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobExecHistory. JobId: "1"; Offset: "0"; Limit: "20" 2018-03-10 12:05:54.1987|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadAllBakups. JobId: "1"; Offset: "0"; Limit: "20" 2018-03-10 12:05:54.1987|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadAllBakups. Return: "Net.Pranas.SqlBak.Log.Backup.BackupItemInfo[]" 2018-03-10 12:05:54.1987|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobExecHistory. Return: "IsSuccess: True; Error: ""; Value: "SBF.Channel.Contract.DataContract.JobExecInfo[]"" 2018-03-10 12:05:54.2047|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|GetCountJobExecHistory. JobId: "1" 2018-03-10 12:05:54.2047|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|GetCountHistoryJob. JobId: "1" 2018-03-10 12:05:54.2047|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|GetCountHistoryJob. Return: "1" 2018-03-10 12:05:54.2047|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|GetCountJobExecHistory. Return: "IsSuccess: True; Error: ""; Value: "1"" 2018-03-10 12:05:54.2047|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobLogDetail. BackupId: "1" 2018-03-10 12:05:54.2047|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadJobBackupLogRecords. LocalBackupId: "1"; IncludeUploaded: "True"; PriorBackupLogId: ""; Limit: "2147483647" 2018-03-10 12:05:54.2047|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadJobBackupLogRecords. Return: "Net.Pranas.SqlBak.Log.Trace.JobBackupLogRecord[]" 2018-03-10 12:05:54.2047|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadBackupObjectInfo. LocalBackupId: "1" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.DestinationDatabaseHandler|LoadDestinationShortDataInfo. DestintionId: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Reading global id... SqlText: "select [DestinationGlobalId] from [Destination] where [DestinationId] = @DestinationId order by [DestinationVersion] desc limit 1" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. GlobalId: "1" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Reading global id... SqlText: "select [JobGlobalId] from [Job] where [JobId] = @JobId order by [JobVersion] desc limit 1" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. GlobalId: "0" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadBackupObjectInfo. LocalBackupId: "1" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Data.DestinationDatabaseHandler|LoadDestinationShortDataInfo. DestintionId: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:0}" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Reading global id... SqlText: "select [DestinationGlobalId] from [Destination] where [DestinationId] = @DestinationId order by [DestinationVersion] desc limit 1" 2018-03-10 12:05:54.2217|TRACE|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. GlobalId: "1" 2018-03-10 12:05:54.2217|DEBUG|53|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2217|DEBUG|53|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobLogDetail. Return: "IsSuccess: True; Error: ""; Value: "SBF.Channel.Contract.DataContract.JobLogDetail"" 2018-03-10 12:05:54.1437|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.ProgramCommon|GetChannelFactory. Return. State: "Created" 2018-03-10 12:05:54.2357|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|Invoke. WithAttempts: "True"; AttemptCount: "3"; Action: "System.Action1[Net.Pranas.SqlBak.Context.Context.Process.IServiceProcess]”
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|Invoke. Creating the channel…
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|Invoke. Running the channel command…
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|Invoke. The channel command has finished
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|Invoke. Return
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Job.Cli.Program|Run. The process has subscribed to SqlBak service. ProcessId: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.2357|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.ProgramCommon|RunJob
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Job.Cli.ProgramCommon|RunJob. Running job… JobId: “1”; BackupType: “Full”; RunMode: “Manually”
2018-03-10 12:05:54.2357|DEBUG|28|Pranas.Net.SqlBak.Service.Context.ServiceProcess|Subscribe. Subscribing process: “589df054-cecc-48a6-bb28-02ffe6f97400”
2018-03-10 12:05:54.2357|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.Business.JobCommands|RunJob. JobId: {LocalId:1; GlobalId:0}
2018-03-10 12:05:54.2357|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Messages.Data.Job.JobDataInfo]" 2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|DEBUG|56|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return 2018-03-10 12:05:54.2357|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: "True"; AttemptCount: "3"; AttemptPause: "3"; Func: "System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Log.Trace.JobPlanViolationsInfo]”
2018-03-10 12:05:54.2357|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client…
2018-03-10 12:05:54.2512|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command…
2018-03-10 12:05:54.2512|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.LocalNetPipeService.LocalJobTraceService|GetJobPlanViolations. JobId: “{LocalId:1; GlobalId:1}”
2018-03-10 12:05:54.2512|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.2512|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.2512|TRACE|52|Net.Pranas.SqlBak.Job.Cli.Business.JobCommands|RunJob. The job remote info. JobId: “{LocalId:1; GlobalId:0}”; ClientId: “”; JobVersion: “”; JobDateDeleteAt: “”; ForbiddenDetail: “”
2018-03-10 12:05:54.2678|DEBUG|52|Net.Pranas.SqlBak.Log.Core.SystemEventLog|Write. IsAsync: “False”; EntryType: “Information”; EventId: “1001”; Message: “Starting “Backup Job - 1” SQL backup job. Backup type: “Full”.”; StackTrace: “”
2018-03-10 12:05:54.2678|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “1000”; Action: “System.Action1[Net.Pranas.SqlBak.Log.Trace.IClientTrace]" 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. Creating the channel... 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. The channel has created 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. Running the channel command... 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. Closing the channel... Timeout: "00:02:30" 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingA. Return 2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Job.Cli.Business.JobCommands|RunJob. Executing the job... 2018-03-10 12:05:54.2678|DEBUG|52|Net.Pranas.SqlBak.Job.Core.JobBase|Run. Running job. JobId: "{LocalId:1; GlobalId:1}"; JobType: "Backup"; MessageId: ""; BackupType: "Full"; RunMode: "Manually" 2018-03-10 12:05:54.2678|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: "True"; AttemptCount: "3"; AttemptPause: "3"; Func: "System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SBF.Defs.Def.ApplicationType]”
2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client…
2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command…
2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.2678|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.2848|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Log.Sbf.SubscriptionFeature.SubscriptionFeatures]" 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.2848|DEBUG|54|Pranas.Net.SqlBak.Service.SBF.WebLog.WebLogHandler|GetEndPointWebLogByJob. JobId: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2848|DEBUG|54|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. DoubleId: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2848|DEBUG|54|Net.Pranas.SqlBak.Context.Context.Ext.DoubleIdExt|SyncToLocalDb. Return: "{LocalId:1; GlobalId:1}" 2018-03-10 12:05:54.2848|DEBUG|54|Pranas.Net.SqlBak.Service.SBF.WebLog.WebLogHandler|GetEndPointWebLogByJob. Return: "https://sqlbackupandftp.com/weblog?jobid=07ee242e-0f41-4f3d-b9b5-e6210b7fe8a9" 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return 2018-03-10 12:05:54.2848|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: "True"; AttemptCount: "3"; AttemptPause: "3"; Func: "System.Func2[Net.Pranas.SqlBak.Log.ClientContent.IClientContent,System.String]”
2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client…
2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command…
2018-03-10 12:05:54.2848|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadJobCredentials. Request: “JobCredentialsId:”"; JobId: “{LocalId:1; GlobalId:1}”"
2018-03-10 12:05:54.2848|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadJobCredentials. Return: “<?xml version="1.0" encoding="utf-16"?>”
2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Job.Core.JobBase|Run. Selecting working folders…
2018-03-10 12:05:54.2848|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Log.ClientContent.IClientContent,Net.Pranas.SqlBak.Log.ClientContent.SqlConnectionRaw]" 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.2848|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.2988|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadSqlConnection. Request: "JobCredentialsId:""; JobId: "{LocalId:1; GlobalId:1}"" 2018-03-10 12:05:54.2988|TRACE|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadSqlConnection. Executing query... Text: "select q2.[SqlConnection], q2.[ServerType], [Name] from [Job] q1 inner join [JobCredentials] q2 on q2.[JobCredentialsId] = q1.[JobCredentialsId] where q1.[JobId] = @JobId" 2018-03-10 12:05:54.2988|TRACE|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadSqlConnection. Sql connection loaded 2018-03-10 12:05:54.2988|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.ClientContentDatabaseHandler|LoadSqlConnection. Return: "Net.Pranas.SqlBak.Log.ClientContent.SqlConnectionRaw" 2018-03-10 12:05:54.2988|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.2988|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return 2018-03-10 12:05:54.2988|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: "True"; AttemptCount: "3"; AttemptPause: "3"; Func: "System.Func2[Net.Pranas.SqlBak.Defs.Service.IConfigService,Net.Pranas.SqlBak.Defs.Model.AgentIdentificator]”
2018-03-10 12:05:54.2988|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client…
2018-03-10 12:05:54.2988|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command…
2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Job.Core.JobBase|Run. BackupType: “Full” AgentId:“df0080b1-a4ab-44d9-8157-ff8f42902d1c”; HasDatabases: “True”; HasDestinations: “True”; JobType: “Backup”
2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Job.Core.JobBase|Run. Begin job. JobId: “{LocalId:1; GlobalId:1}”
2018-03-10 12:05:54.3498|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Log.Trace.JobRunParameters]" 2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.3498|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.3608|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.LocalNetPipeService.LocalJobTraceService|BeginJob. JobId: "{LocalId:1; GlobalId:1}"; MessageId: ""; BackupType: "f"; BackupKey: "2ed9780aa10055ca118e42d8cad37794" 2018-03-10 12:05:54.4838|DEBUG|57|Pranas.Net.SqlBak.Service.Data.ConfigService|GetStaticProductVersion 2018-03-10 12:05:54.4958|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|TraceBeginJob. JobId: "{LocalId:1; GlobalId:1}"; BackupKey: "2ed9780aa10055ca118e42d8cad37794" 2018-03-10 12:05:54.4958|TRACE|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|TraceBeginJob. Inserting into [Backup] table... 2018-03-10 12:05:54.5048|TRACE|51|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.5048|TRACE|51|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return 2018-03-10 12:05:54.5048|TRACE|51|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|CheckEmailList. validateResult.length: 0 2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Controllers.JobsController|RaiseJobStatusUpdated. JobId: "1"; Status: "Running"; Message: "" 2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Commands.LoadJobExecLogItemsCommand|DoExecute. Parameter: "1" 2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Commands.LoadJobExecLogItemsCommand|LoadExecLogItems 2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Commands.LoadJobExecLogItemsCommand|LoadExecLogItems. Return: "System.Collections.ObjectModel.ObservableCollection1[Net.Pranas.SBF.Context.Model.Job.JobExecLogItemModel]”
2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Job.Commands.LoadJobExecLogItemsCommand|DoExecute. Return
2018-03-10 12:05:54.1917|DEBUG|16|Net.Pranas.SBF.Context.Model.Tools.Pagination.Command.LoadPagesCommand|DoExecute. Parameter: “0”
2018-03-10 12:05:54.2107|DEBUG|16|Net.Pranas.SBF.Context.Model.JobDetailLog.Command.LoadJobDetailLogCommand|DoExecute. Parameter: “”
2018-03-10 12:05:54.5118|DEBUG|16|Net.Pranas.SBF.Context.Model.JobDetailLog.Command.LoadJobDetailLogCommand|DoExecute. Return
2018-03-10 12:05:54.5178|DEBUG|1|Net.Pranas.SQLBackupAndFTP.Control.Job.BackupJobSettingsControl|CheckJobCustomization
2018-03-10 12:05:54.5178|DEBUG|1|Net.Pranas.SQLBackupAndFTP.Control.Job.BackupJobSettingsControl|CheckJobCustomization
2018-03-10 12:05:54.5178|DEBUG|1|Net.Pranas.SQLBackupAndFTP.Control.Job.BackupJobSettingsControl|CheckJobCustomization
2018-03-10 12:05:54.5178|DEBUG|1|Net.Pranas.SQLBackupAndFTP.Control.Job.BackupJobSettingsControl|CheckJobCustomization
2018-03-10 12:05:54.7048|TRACE|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|TraceBeginJob. The new record has successfully inserted. LocalBackupId: “2”
2018-03-10 12:05:54.7048|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|TraceBeginJob. Return: “2”
2018-03-10 12:05:54.7048|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobExecHistory. JobId: “1”; Offset: “0”; Limit: “20”
2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.7048|DEBUG|52|Net.Pranas.SqlBak.Job.Cli.Business.JobCommands|RunJob. OnInitializeJob. LocalBackupId: “2”
2018-03-10 12:05:54.7048|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadAllBakups. JobId: “1”; Offset: “0”; Limit: “20”
2018-03-10 12:05:54.7048|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: “True”; AttemptCount: “3”; AttemptPause: “3”; Func: “System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Log.Sbf.License.LicenseInfo]" 2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client... 2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command... 2018-03-10 12:05:54.7048|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|LoadAllBakups. Return: "Net.Pranas.SqlBak.Log.Backup.BackupItemInfo[]" 2018-03-10 12:05:54.7048|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|LoadJobExecHistory. Return: "IsSuccess: True; Error: ""; Value: "SBF.Channel.Contract.DataContract.JobExecInfo[]"" 2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed 2018-03-10 12:05:54.7048|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return 2018-03-10 12:05:54.7048|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|GetCountJobExecHistory. JobId: "1" 2018-03-10 12:05:54.7048|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|GetCountHistoryJob. JobId: "1" 2018-03-10 12:05:54.7208|DEBUG|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. WithAttempts: "True"; AttemptCount: "3"; AttemptPause: "3"; Func: "System.Func2[Net.Pranas.SqlBak.Log.Trace.IJobTrace,Net.Pranas.SqlBak.Log.Sbf.License.LicenseInfo]”
2018-03-10 12:05:54.7208|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Creating the channel client…
2018-03-10 12:05:54.7208|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Running the channel command…
2018-03-10 12:05:54.7208|DEBUG|57|Net.Pranas.SqlBak.Context.Context.Data.RoutinesDatabaseHandler|GetCountHistoryJob. Return: “2”
2018-03-10 12:05:54.7208|DEBUG|57|Pranas.Net.SqlBak.Service.SBF.DesktopAppService|GetCountJobExecHistory. Return: "IsSuccess: True; Error: “”; Value: “2"”
2018-03-10 12:05:54.7208|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|CloseClient. The channel client has closed
2018-03-10 12:05:54.7208|TRACE|52|Net.Pranas.SqlBak.Tools.Wcf.WcfExtensions|UsingF. Return
2018-03-10 12:05:54.7518|DEBUG|52|Net.Pranas.SqlBak.Job.Core.JobBase|Run. Db connection is ok


#2

Hi Jonny,

Thanks for the log, we have checked it and it seems everything works properly.

To investigate this case we need more details. Could you please send us the “Advanced Log”? You can find how to do it at https://sqlbackupandftp.com/blog/how-to-send-log-to-developers

Sorry for the inconvenience.


#3

I already sent the log.


#4

Hi Jonny,

Sorry, but we haven’t received your logs, could you please send them again?

Sorry for the inconvenience.


#5

Hi Alexander

I sent you again.

If you dont received, here is the complete log.

https://1drv.ms/u/s!AvrkWUTHnsZQb2mtBF580LovZuc


#6

Hi Jonny,

Thank you for the details, we have received your logs. Please give us some time to investigate this issue.

Also, we recommend you to try to reauthorize to your OneDrive destination.

Sorry for the inconvenience.