Qlik Community

QlikView Publisher

Discussion Board for collaboration on QlikView Publisher.

Not applicable

Reload log shows 'Execution finished' but 'Reload Failed'

Hello,

We have a QlikView .qvw reload which runs once an hour (and has done for > 12 months). There have been no recent modifications to the .qvw. The reload itself typically runs for about 35+ minutes.

For the past three hours, it has been coughing up this fur-ball:

The task "1h Reload JDE (xx:00)" failed. Part
of the log file follows:

12.09.2012 16:01:45     Error The Source Document was NOT reloaded
successfully. DocumentPath=D:\Qlikview\Data\JDE\TWI_Extract.qvw.

12.09.2012 16:01:45     Error Reload failed.

12.09.2012 16:01:46     Error Reload failed. Distribution skipped.

12.09.2012 16:01:46     Error The task "1h Reload JDE (xx:00)"
failed. ErrorCount=3

12.09.2012 16:02:07     Error Aborting Reload. Error=QDSMain.TaskResult

12.09.2012 16:41:36     Error Reload was aborted.

12.09.2012 16:41:37     Error Reload failed.

12.09.2012 16:41:37     Error Reload failed. Distribution skipped.

12.09.2012 16:41:37     Error The task "1h Reload JDE (xx:00)"
failed. ErrorCount=4

Looking in the job's log file, it appears as if it reloaded normally to completion:

...

9/12/2012 4:41:36 PM:        4 fields found: ACCESS, NTNAME, Reporting Code 16, Reporting Code 17, 75 lines fetched

9/12/2012 4:41:36 PM: 0471  Store * from CatCode17 into CatCode17.QVD

9/12/2012 4:41:36 PM: 0472  Store * from CatCode17 into CatCode17.txt (txt)

9/12/2012 4:41:36 PM: 0475  DROP Table CatCode17

9/12/2012 4:41:36 PM:      Execution finished.

Looking at the log in QV Enterprise manager, I see this:

(2012-09-12 16:00:00) Information: Executing ClusterID=1, QDSID=521ab837-9c17-0a46-43e0-6db7b10d30e5 QDSMain.DistributeTask

(2012-09-12 16:00:00) Information: Starting task "1h Reload JDE (xx:00)" (Attempt 1 of 2)

(2012-09-12 16:00:00) Information: Max run time: 1.00:00:00

(2012-09-12 16:00:00) Information: Document is marked for refresh; Initializing Reload.

(2012-09-12 16:00:00) Information: Opening "D:\Qlikview\Data\JDE\TWI_Extract.qvw"

(2012-09-12 16:00:00) Information: Allocating QlikView Engine

(2012-09-12 16:00:30) Information: Allocating new QlikView Engine. Current usagecount=3 of 3

(2012-09-12 16:00:30) Information: An instance of the QlikView Engine is being created

(2012-09-12 16:00:30) Information: Starting QlikView Engine

(2012-09-12 16:00:31) Information: Allocated QlikView Engine successfully. Current usagecount=3 of 3, Ticket=22683

(2012-09-12 16:00:31) Information: Loading document "D:\Qlikview\Data\JDE\TWI_Extract.qvw" (0.16 Mb)

(2012-09-12 16:00:31) Information: Physical FileSize=0.16 Mb. Memory Allocation Delta for this file=4.23 Mb. Available Physical Memory Before Open=1372.21 Mb. Available Physical Memory After Open=1364.04 Mb. Total Physical Memory=8190.60 Mb.

(2012-09-12 16:00:31) Information: The document was loaded successfully.

(2012-09-12 16:00:31) Information: Initializing Checkout (0), Loading document (30750), Initializing load (30750), Checking read (30750), Creating QlikView Engine (30782), Checking Size (30813), Load (30813), Load Completed (30891), Tables read (31079), Opened successfully (31094)

(2012-09-12 16:00:31) Information: Document was opened successfully

(2012-09-12 16:00:31) Information: Opened successfully (0)

(2012-09-12 16:00:31) Information: Starting reload

(2012-09-12 16:00:31) Information: QlikView->Settings->Document Preferences->Generate Logfile in document is set. Enabling Log Copier.

(2012-09-12 16:00:31) Information: Waiting for the document log "D:\Qlikview\Data\JDE\TWI_Extract.qvw" to become available..

(2012-09-12 16:00:31) Information: The Source Document is being reloaded. DocumentPath=D:\Qlikview\Data\JDE\TWI_Extract.qvw

(2012-09-12 16:00:31) Information: Found a document log. FileName=D:\Qlikview\Data\JDE\TWI_Extract.qvw.2012_09_12_16_00_31.log

(2012-09-12 16:00:31) Information: Writing documentLog to C:\ProgramData\QlikTech\DistributionService\1\Log\20120912\160000 - 1h Reload JDE (xx_00)\DocumentLog.txt

(2012-09-12 16:00:32) Information: Reloading.

(2012-09-12 16:00:33) Information: Reloading..

(2012-09-12 16:00:34) Information: Reloading...

(2012-09-12 16:00:35) Information: Reloading....

(2012-09-12 16:00:36) Information: Reloading.....

(2012-09-12 16:00:37) Information: Reloading......

(2012-09-12 16:00:38) Information: Reloading.......

(2012-09-12 16:00:39) Information: Reloading........

(2012-09-12 16:00:40) Information: Reloading.........

(2012-09-12 16:00:41) Information: Reloading..........

(2012-09-12 16:00:42) Information: Slowing down logging. LoggingSeconds=2

(2012-09-12 16:00:42) Information: Reloading

(2012-09-12 16:00:44) Information: Reloading.

(2012-09-12 16:00:46) Information: Reloading..

(2012-09-12 16:00:48) Information: Reloading...

(2012-09-12 16:00:50) Information: Reloading....

(2012-09-12 16:00:52) Information: Reloading.....

(2012-09-12 16:00:54) Information: Reloading......

(2012-09-12 16:00:56) Information: Reloading.......

(2012-09-12 16:00:58) Information: Reloading........

(2012-09-12 16:01:00) Information: Reloading.........

(2012-09-12 16:01:02) Information: Reloading..........

(2012-09-12 16:01:04) Information: Slowing down logging. LoggingSeconds=10

(2012-09-12 16:01:04) Information: Reloading

(2012-09-12 16:01:14) Information: Reloading.

(2012-09-12 16:01:24) Information: Reloading..

(2012-09-12 16:01:34) Information: Reloading...

(2012-09-12 16:01:44) Information: Reloading....

(2012-09-12 16:01:45) Information: The Source Document reload complete. DocumentPath=D:\Qlikview\Data\JDE\TWI_Extract.qvw

(2012-09-12 16:01:45) Information: Memory Allocation Delta for this file=50.98 Mb. Available Physical Memory Before Reload=1362.61 Mb. Available Physical Memory After Reload=514.48 Mb. Total Physical Memory=514.48 Mb.

(2012-09-12 16:01:45) Error: The Source Document was NOT reloaded successfully. DocumentPath=D:\Qlikview\Data\JDE\TWI_Extract.qvw.

(2012-09-12 16:01:45) Information: Initializing Reload (0), Finished (73906)

(2012-09-12 16:01:45) Error: Reload failed.

(2012-09-12 16:01:45) Information: Closing the document.

(2012-09-12 16:01:46) Information: Closed the QlikView Engine successfully. ProcessID=5364

(2012-09-12 16:01:46) Information: Initializing (0), Finished (735)

(2012-09-12 16:01:46) Error: Reload failed. Distribution skipped.

(2012-09-12 16:01:46) Error: The task "1h Reload JDE (xx:00)" failed. ErrorCount=3

(2012-09-12 16:01:46) Information: ----------------------------------------------

(2012-09-12 16:01:46) Information: Retrying!

(2012-09-12 16:01:46) Information: Executing ClusterID=1, QDSID=521ab837-9c17-0a46-43e0-6db7b10d30e5 QDSMain.DistributeTask

(2012-09-12 16:01:46) Information: Starting task "1h Reload JDE (xx:00)" (Attempt 2 of 2)

(2012-09-12 16:01:46) Information: Max run time: 1.00:00:00

(2012-09-12 16:01:46) Information: Document is marked for refresh; Initializing Reload.

(2012-09-12 16:01:46) Information: Opening "D:\Qlikview\Data\JDE\TWI_Extract.qvw"

(2012-09-12 16:01:46) Information: Allocating QlikView Engine

(2012-09-12 16:01:48) Information: Failed to allocate new QlikView Engine. Retrying (2)! Current usagecount=3 of 3

(2012-09-12 16:02:05) Information: Allocating new QlikView Engine. Current usagecount=3 of 3

(2012-09-12 16:02:05) Information: An instance of the QlikView Engine is being created

(2012-09-12 16:02:05) Information: Starting QlikView Engine

(2012-09-12 16:02:06) Information: Allocated QlikView Engine successfully. Current usagecount=3 of 3, Ticket=22688

(2012-09-12 16:02:06) Information: Loading document "D:\Qlikview\Data\JDE\TWI_Extract.qvw" (0.16 Mb)

(2012-09-12 16:02:06) Information: Physical FileSize=0.16 Mb. Memory Allocation Delta for this file=4.28 Mb. Available Physical Memory Before Open=723.43 Mb. Available Physical Memory After Open=721.25 Mb. Total Physical Memory=8190.60 Mb.

(2012-09-12 16:02:06) Information: The document was loaded successfully.

(2012-09-12 16:02:06) Information: Initializing Checkout (0), Loading document (20407), Initializing load (20407), Checking read (20407), Creating QlikView Engine (20422), Checking Size (20422), Load (20422), Load Completed (20454), Tables read (20469), Opened successfully (20469)

(2012-09-12 16:02:06) Information: Document was opened successfully

(2012-09-12 16:02:06) Information: Opened successfully (0)

(2012-09-12 16:02:06) Information: Starting reload

(2012-09-12 16:02:06) Information: QlikView->Settings->Document Preferences->Generate Logfile in document is set. Enabling Log Copier.

(2012-09-12 16:02:06) Information: Waiting for the document log "D:\Qlikview\Data\JDE\TWI_Extract.qvw" to become available..

(2012-09-12 16:02:06) Information: The Source Document is being reloaded. DocumentPath=D:\Qlikview\Data\JDE\TWI_Extract.qvw

(2012-09-12 16:02:06) Information: Found a document log. FileName=D:\Qlikview\Data\JDE\TWI_Extract.qvw.2012_09_12_16_02_06.log

(2012-09-12 16:02:06) Information: Writing documentLog to C:\ProgramData\QlikTech\DistributionService\1\Log\20120912\160000 - 1h Reload JDE (xx_00)\DocumentLog.txt

(2012-09-12 16:02:07) Information: Reloading.

(2012-09-12 16:02:07) Error: Aborting Reload. Error=QDSMain.TaskResult

(2012-09-12 16:41:36) Error: Reload was aborted.

(2012-09-12 16:41:36) Information: Closing the document.

(2012-09-12 16:41:37) Information: Initializing Reload (0), Finished (2370422)

(2012-09-12 16:41:37) Error: Reload failed.

(2012-09-12 16:41:37) Information: Closed the QlikView Engine successfully. ProcessID=2068

(2012-09-12 16:41:37) Information: Initializing (0), Finished (0)

(2012-09-12 16:41:37) Error: Reload failed. Distribution skipped.

(2012-09-12 16:41:37) Error: The task "1h Reload JDE (xx:00)" failed. ErrorCount=4

(2012-09-12 16:41:37) Information: Sending Alert Mail to 2 recipients.

(2012-09-12 16:41:37) Information: Closed the QlikView Engine successfully. ProcessID=2068

(2012-09-12 16:41:37) Error: All retries failed.

No user aborted the reload, plus the log file indicates that it actually completed. The .qvw itself loads no data - it simply opens tables from an Oracle database and writes them to .qvd's before closing.

There is ample room both on the system's System disk as well as the data disk on which the .qvw's/.qvd's reside.

I see no relevant error messages in the system event log.

Any help welcome.

26 Replies
Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

...and after three hourly failures - this time it ran successfully through to completion without any changes to the environment. But... it is failing three or four times a day now with little to go on to troubleshoot.

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Hi,

I have the same situation here. Do you have any solution right now?

would it be possible that there are not enough thread in the database server?

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

I have no solution nor have I heard more than you see above (i.e., nothing). Our system has been running smoothly (again) in the meantime, but I'm guessing that the situation will return. Wouldn't mind hearing from someone at QlikTech who could theorize based upon their knowledge of the inner workings.

paul_w_routledg
Contributor II

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Sorry I have no solutionm, we are having similar problems and came across this.

I also noticed another post on the exact seem topic so linking to it here

http://community.qlik.com/message/262594#262594

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Had the same problem again today - with a different, much smaller task.

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Note: the other post (thanks for the link!) is actually slightly different. That's sporadic failures. The log file shows the job aborting.

In my case, the log files states that execution finished (normally), but then a mail is sent showing 'reload failed'.

lucas4bi
Contributor

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Is it possible that the system feels the QVDs you are trying to write with this application like they are still in use by another process?

Like they are opened somewhere else and so qlikview can't write on the files?

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Unfortunately, no. The first job extracts tables from an Oracle database. Only when it finishes successfully do other jobs run which use the .qvd files. The job runs to completion (Execution Finished in the log file) but then claims to fail. As a result, those other .qvw's which would use the .qvd's never start. They're scheduled to run only when the first is successful.

Not applicable

Re: Reload log shows 'Execution finished' but 'Reload Failed'

Problem still occurring. Do we think that QlikTech actually visits this end of the QlikView world... or is there some other place to post this that they'd see..?

Community Browser