Need help in solving issue The Source Document was NOT reloaded successfully

[#1]

Background: We are making a SharePoint 2010 REST call to pull data from a SP list within the qvw.

The job is scheduled and runs fine, but fails some times may be I would say twice a week (Randomly)

but when we run the job manually the next day morning it completes fine. Even if we wait for next day it completes execution fine.

The error message from the log file

The task "NPP/Reload of Deployed\QVDBuilders\Layer1\QVD_PSIP_Workflow.qvw" failed. Part of the log file follows:

1/5/2015 2:00:03 AM    Error    The Source Document was NOT reloaded successfully. DocumentPath=F:\QlikView\SourceDocuments\Product_Lines\New_Product_Development\Deployed\QVDBuilders\Layer1\QVD_PSIP_Workflow.qvw.
1/5/2015 2:00:05 AM    Error    The task "NPP/Reload of Deployed\QVDBuilders\Layer1\QVD_PSIP_Workflow.qvw" failed. Exception:
QDSMain.Exceptions.DistributionFailedException: Distribute failed with errors to follow. ---> QDSMain.Exceptions.ReloadFailedException: Reload failed ---> QDSMain.Exceptions.LogBucketErrorException: The Source Document was NOT reloaded successfully. DocumentPath=F:\QlikView\SourceDocuments\Product_Lines\New_Product_Development\Deployed\QVDBuilders\Layer1\QVD_PSIP_Workflow.qvw.
   at QDSMain.ReloadTask.VerifyConditions(TaskResult taskResult)
   at QDSMain.ReloadTask.Reload(String fileName, TaskResult taskResult, String sectionAccessUserName, String sectionAccessPassword, eReloadOptions reloadOption, String variableName, String variableValue, Boolean moniterCpuUsage)
   --- End of inner exception stack trace ---
   at QDSMain.ReloadTask.Reload(String fileName, TaskResult taskResult, String sectionAccessUserName, String sectionAccessPassword, eReloadOptions reloadOption, String variableName, String variableValue, Boolean moniterCpuUsage)
   at QDSMain.DistributeTask.Execute(TaskResult currentTaskResult)
   --- End of inner exception stack trace ---
   at QDSMain.DistributeTask.Execute(TaskResult currentTaskResult)
   at QDSMain.Task.AbstractTask.TaskExecution(ILogBucket logBucket, TaskResult taskResult)

[Peter_Cammaert]

This is the TaskLog (pretty useless as you can see). If you enabled Generate Log File in Settings->Document Properties->General of document QVD_PSIP_Workflow.qvw, then there should also be a DocumentLog. That may contain the reason why the task fails (once in a while).

Because the QDS keeps 30 days worth of log files, I'm sure you can find one that reports on a failed reload.

Can you post that part?

Peter

[#3]

Here is the log file contents, but there is no clue from the log.

1/3/2015 01:00:02.5561060           Information       1/3/2015 1:00:02 AM:      Execution started.

1/3/2015 01:00:02.5800916           Information       1/3/2015 1:00:02 AM:      QlikView Version:11.20.12354.0

1/3/2015 01:00:02.5820935           Information       1/3/2015 1:00:02 AM:      CPU Target                    x64

1/3/2015 01:00:02.5850966           Information       1/3/2015 1:00:02 AM:      Operating System              Windows Server 2012 Standard  (64 bit edition)

1/3/2015 01:00:02.5870986           Information       1/3/2015 1:00:02 AM:      Wow64 mode                    Not using Wow64

1/3/2015 01:00:02.5901013           Information       1/3/2015 1:00:02 AM:      MDAC Version                  6.2.9200.16384

1/3/2015 01:00:02.5931044           Information       1/3/2015 1:00:02 AM:      MDAC Full Install Version     6.2.9200.16384

1/3/2015 01:00:02.5961075           Information       1/3/2015 1:00:02 AM:      PreferredCompression          2

1/3/2015 01:00:02.5981091           Information       1/3/2015 1:00:02 AM:      EnableParallelReload          1

1/3/2015 01:00:02.6011122           Information       1/3/2015 1:00:02 AM:      ParallelizeQvdLoads           1

1/3/2015 01:00:02.6111220           Information       1/3/2015 1:00:02 AM:      AutoSaveAfterReload           0

1/3/2015 01:00:02.6141248           Information       1/3/2015 1:00:02 AM:      BackupBeforeReload            0

1/3/2015 01:00:02.6171279           Information       1/3/2015 1:00:02 AM:      EnableFlushLog                0

1/3/2015 01:00:02.6201310           Information       1/3/2015 1:00:02 AM:      SaveInfoWhenSavingFile        0

1/3/2015 01:00:02.6231351           Information       1/3/2015 1:00:02 AM:      UserLogfileCharset            1200

1/3/2015 01:00:02.6261355           Information       1/3/2015 1:00:02 AM:      OdbcLoginTimeout              -1

1/3/2015 01:00:02.6291396           Information       1/3/2015 1:00:02 AM:      OdbcConnectionTimeout         -1

1/3/2015 01:00:02.6321427           Information       1/3/2015 1:00:02 AM:      ScriptWantsDbWrite            false

1/3/2015 01:00:02.6341450           Information       1/3/2015 1:00:02 AM:      ScriptWantsExe                false

1/3/2015 01:00:02.6371518           Information       1/3/2015 1:00:02 AM:      LogFile CodePage Used:        1200

1/3/2015 01:00:02.6401529           Information       1/3/2015 1:00:02 AM:       Reload Executed By abcd\svc_qlikview

1/3/2015 01:00:02.6431540           Information       1/3/2015 1:00:02 AM:       Process Executing: QVB

1/3/2015 01:00:02.6461632           Information       1/3/2015 1:00:02 AM:       Process ID: 11568

1/3/2015 01:00:02.6491605           Information       1/3/2015 1:00:02 AM: 0002  SET ThousandSep=','

1/3/2015 01:00:02.6521622           Information       1/3/2015 1:00:02 AM: 0003  SET DecimalSep='.'

1/3/2015 01:00:02.6541642           Information       1/3/2015 1:00:02 AM: 0004  SET MoneyThousandSep=','

1/3/2015 01:00:02.6571669           Information       1/3/2015 1:00:02 AM: 0005  SET MoneyDecimalSep='.'

1/3/2015 01:00:02.6601929           Information       1/3/2015 1:00:02 AM: 0006  SET MoneyFormat='$#,##0.00;($#,##0.00)'

1/3/2015 01:00:02.6631728           Information       1/3/2015 1:00:02 AM: 0007  SET TimeFormat='h:mm:ss TT'

1/3/2015 01:00:02.6661756           Information       1/3/2015 1:00:02 AM: 0008  SET DateFormat='M/D/YYYY'

1/3/2015 01:00:02.6691787           Information       1/3/2015 1:00:02 AM: 0009  SET TimestampFormat='M/D/YYYY h:mm:ss[.fff] TT'

1/3/2015 01:00:02.6721814           Information       1/3/2015 1:00:02 AM: 0010  SET MonthNames='Jan;Feb;Mar;Apr;May;Jun;Jul;Aug;Sep;Oct;Nov;Dec'

1/3/2015 01:00:02.6751845           Information       1/3/2015 1:00:02 AM: 0011  SET DayNames='Mon;Tue;Wed;Thu;Fri;Sat;Sun'

1/3/2015 01:00:06.6901017           Information       1/3/2015 1:00:02 AM: 0017  PSIP_Workflow_Summary:

1/3/2015 01:00:06.7721818           Information       1/3/2015 1:00:02 AM: 0018  Load

1/3/2015 01:00:06.7781887           Information       1/3/2015 1:00:02 AM: 0019      [content/properties/ProjectId] as project_id,

1/3/2015 01:00:06.7851951           Information       1/3/2015 1:00:02 AM: 0020      [content/properties/ProjectName] as SummaryProjectName,

1/3/2015 01:00:06.7932053           Information       1/3/2015 1:00:02 AM: 0021      [content/properties/SequenceNumber] as SummarySequenceNumber,

1/3/2015 01:00:06.8352442           Information      1/3/2015 1:00:02 AM: 0022      [content/properties/Milestone] as Milestone,       

1/3/2015 01:00:06.9113226           Information       1/3/2015 1:00:02 AM: 0023      [content/properties/StatusValue] as SummaryStatusValue,

1/3/2015 01:00:06.9203268           Information       1/3/2015 1:00:02 AM: 0024      [content/properties/OriginatorFullName] as SummaryOriginatorFull,

1/3/2015 01:00:06.9283339           Information       1/3/2015 1:00:02 AM: 0025      [content/properties/Approvers] as SummaryApprovers,       

1/3/2015 01:00:06.9353416           Information       1/3/2015 1:00:02 AM: 0026 

1/3/2015 01:00:06.9433484           Information       1/3/2015 1:00:02 AM: 0027 

1/3/2015 01:00:07.0224274           Information       1/3/2015 1:00:02 AM: 0028 

1/3/2015 01:00:07.0284326           Information       1/3/2015 1:00:02 AM: 0029 

1/3/2015 01:00:07.0914930           Information       1/3/2015 1:00:02 AM: 0030      date(left([content/properties/Modified],10)) as Summary_Modified_date,

1/3/2015 01:00:07.0985010           Information       1/3/2015 1:00:02 AM: 0031      date(left([content/properties/Created],10)) as Summary_Created_date,

1/3/2015 01:00:07.1075106           Information       1/3/2015 1:00:02 AM: 0032      [content/properties/ProjectId] & [content/properties/Milestone] as Wf_key

1/3/2015 01:00:07.1165182           Information       1/3/2015 1:00:02 AM: 0033 

1/3/2015 01:00:07.1225238           Information       1/3/2015 1:00:02 AM: 0034 

1/3/2015 01:00:07.1285303           Information       1/3/2015 1:00:02 AM: 0035 

1/3/2015 01:00:07.1815823           Information       1/3/2015 1:00:02 AM: 0036 

1/3/2015 01:00:07.1875874           Information       1/3/2015 1:00:02 AM: 0037 

1/3/2015 01:00:07.1935943           Information       1/3/2015 1:00:02 AM: 0038  FROM [http://abcd.com/_vti_bin/listdata.svc/Milestones?$skip=0&$top=1000] (XmlSimple, Table is [feed/entry])

1/3/2015 01:00:07.2026023           Information       1/3/2015 1:00:06 AM:       Error: The download of the specified resource has failed.

1/3/2015 01:00:07.2116136           Information       1/3/2015 1:00:06 AM:       General Script Error

1/3/2015 01:00:07.2176164           Information       1/3/2015 1:00:06 AM:       Execution Failed

1/3/2015 01:00:07.2246258           Information       1/3/2015 1:00:06 AM:      Execution finished

[Peter_Cammaert]

Sorry to correct you but previously we (the community) had no clue. Now we do, more or less. You're right that this error message doesn't really tell you much, except that the SharePoint page was either:

• unavailable
• taking too much time to produce the data
• producing no data at all (might be temporary)
• hickupping (network trouble)

There may be other reasons why you are experiencing this problem every so often, but it clearly points to a communications problem. Can you detect a pattern in the times of failure? Is the SP site busy at that time? Is there a backup running, for example at night? Just trying to think of something...

Best,

Peter