SoftTree Technologies SoftTree Technologies
Technical Support Forums
RegisterSearchFAQMemberlistUsergroupsLog in
5.2.417 semaphore check buggy

 
Reply to topic    SoftTree Technologies Forum Index » 24x7 Scheduler, Event Server, Automation Suite View previous topic
View next topic
5.2.417 semaphore check buggy
Author Message
barefootguru



Joined: 10 Aug 2007
Posts: 195

Post 5.2.417 semaphore check buggy Reply with quote
Hi, just upgraded from 5.1.403 to 5.2.417

Every month or so the old version would ignore semaphore files, so the scheduling would grind to a halt. This version it's happening every few hours.

- time based jobs will still run, and create FINISH files in the 24x7 directory
- the file based jobs won't start even though the FINISH jobs are sitting there

Restart the scheduler and the file-based jobs will start.

Need to sort this quickly. Is there a beta available which fixes this? If not I'll have to downgrade, which is a pain as I need to downgrade Java too.

Windows 8.1 Pro. Running as a service but also affects GUI.
Thu Jun 19, 2014 4:16 pm View user's profile Send private message
SysOp
Site Admin


Joined: 26 Nov 2006
Posts: 7841

Post Reply with quote
Could you please add Handle Count column to the Task Scheduler and see if it keeps increasing and then stops at a certain point?
Thu Jun 19, 2014 5:00 pm View user's profile Send private message
barefootguru



Joined: 10 Aug 2007
Posts: 195

Post Reply with quote
Thanks for the quick response but can't debug this any further: the 5.2.417 GUI was hanging whenever I made a change to the job database, e.g. enabling a job, editing job properties using the wizard.

Have reverted to 5.1.403. Will add to this thread if I manage to strike the same problem again.

FWIW here's the handle usage over 45 minutes:

Code:

time
PID, name, handles

0940
4708 java.exe 1368
2764 24x7srv.exe 47

0945
4708 java.exe 1573
2764 24x7srv.exe 47
968 svchost.exe 1663
896 svchost.exe 1235

1000
4708 java.exe 1645
2764 24x7srv.exe 47
968 svchost.exe 1673
896 svchost.exe 1232

1015
4708 java.exe 1644
2764 24x7srv.exe 47
968 svchost.exe 1702
896 svchost.exe 1230

Thu Jun 19, 2014 7:33 pm View user's profile Send private message
SysOp
Site Admin


Joined: 26 Nov 2006
Posts: 7841

Post Reply with quote
I checked the internal change log for changes between 5.1.403 and 5.2.417 and there is absolutely nothing referring to the semaphore file processing, or file processing in general.

The only thing I see that could have changed something is switch from JRE 1.6 to JRE 1.7. Version 5.2.417 comes JRE 1.7.0_25 prepackaged. I suggest t0 try upgrading that JRE version to the latest available 1.7.0_55 or 1.7.0_60. Here is the download link
http://javadl.sun.com/webapps/download/AutoDL?BundleId=90223

It should be installed into c:\24x7\JRE subfolder to replace the installed version
Thu Jun 19, 2014 9:00 pm View user's profile Send private message
barefootguru



Joined: 10 Aug 2007
Posts: 195

Post Reply with quote
Thanks, but I installed Java 1.7 update 60 before installing 24x7, as the installer requires Java 1.7 to even run (let alone install the prepackaged version) 8-)
Fri Jun 20, 2014 2:44 am View user's profile Send private message
SysOp
Site Admin


Joined: 26 Nov 2006
Posts: 7841

Post Reply with quote
barefootguru wrote:
Thanks, but I installed Java 1.7 update 60 before installing 24x7, as the installer requires Java 1.7 to even run (let alone install the prepackaged version) 8-)


Hmm.... Something sounds wrong here. The installer does not require any Java to run. As far as I know you are ruining it on a Windows server. Have you used the correct installation program? The installer for Windows platforms is a native Windows executable . The installer installs local JRE instance for use with 24x7 so if you have any other Java installation, it doesn't use it. The product has been compiled , tested, and prepackaged with Java 7.0.25. I now wonder if you got it somehow installed with a different Java version, you may have an incompatibility issue leading to an anomaly in the processing.
Fri Jun 20, 2014 12:06 pm View user's profile Send private message
barefootguru



Joined: 10 Aug 2007
Posts: 195

Post Reply with quote
I downloaded the multi-platform installer version -- it does say it's for Windows too 8-)

Next time I'll try the graphical Windows installer. I do like the idea of it coming with it's own supported & tested version of Java.

Cheers
Tue Jun 24, 2014 6:10 pm View user's profile Send private message
SysOp
Site Admin


Joined: 26 Nov 2006
Posts: 7841

Post Reply with quote
It also does a few other things that the multiplatform installer doesn't do, for example, it installs web console along with the webserver, generates self signed SSL certificate, checks for older installations and copies certain things over, and and so on... Hope it makes sense.
Tue Jun 24, 2014 6:27 pm View user's profile Send private message
barefootguru



Joined: 10 Aug 2007
Posts: 195

Post Reply with quote
Have revisited this and struck the same problem.

1. installed 5.2.417 Windows installer
2. time-based jobs worked, jobs based on semaphore files (in 24x7 directory) didn't

3. upgraded Java to 1.7.0_60
4. no change

5. created brand new job database with single semaphore job
6. job did trigger, though got a weird message about 'An error occurred while executing automation script: Error accessing job database: 0'

I've done a lot of experimenting with Java versions and the Java command line. Calling 'java.exe' instead of 'javaw.exe' was successful (and printed a string of digits). Increasing memory to 1536 didn't help.

My guess is either there's something odd in our job database, or 5.2.417 can't handle 300+ semaphore file checks (almost all in the 24x7 directory).

Here's the complete debug log from starting 24x7, running a few time-based jobs, but semaphore-based jobs being ignored (they have 1 minute check intervals, scheduler was up 3 minutes).

Code:

2015-03-26 16:43:08,431 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - JVM version: 1.7.0_60-b19
2015-03-26 16:43:08,446 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - Current time with timezone: Thu Mar 26 16:43:08 NZDT 2015
2015-03-26 16:43:08,446 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : start
2015-03-26 16:43:08,446 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : start
2015-03-26 16:43:08,696 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating business objects
2015-03-26 16:43:08,884 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating UI controller
2015-03-26 16:43:09,056 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : initializing business objects
2015-03-26 16:43:09,087 [main] DEBUG com.softtreetech.jscheduler.business.preferences.AbstractPrefDatabase - Creating backup for preferences file preferences.xml
2015-03-26 16:43:09,107 [main] DEBUG com.softtreetech.jscheduler.business.preferences.AbstractPrefDatabase - Preferences file has been copied to preferences.bak
2015-03-26 16:43:09,681 [main] DEBUG com.softtreetech.jscheduler.business.dde.DDEServer - Starting DDE service...
2015-03-26 16:43:09,853 [main] DEBUG com.softtreetech.jscheduler.business.dde.DDEServer - DDE service started
2015-03-26 16:43:09,853 [main] DEBUG com.softtreetech.jscheduler.business.Master - Loading calendars...
2015-03-26 16:43:11,071 [main] DEBUG com.softtreetech.jscheduler.business.Master - Loading agent profiles...
2015-03-26 16:43:11,071 [main] DEBUG com.softtreetech.jscheduler.business.Master - Loading queue managers...
2015-03-26 16:43:11,087 [main] DEBUG com.softtreetech.jscheduler.business.Master - Initializing scheduling engine...
2015-03-26 16:43:11,087 [main] DEBUG com.softtreetech.jscheduler.business.Master - Loading job templates...
2015-03-26 16:43:11,118 [main] DEBUG com.softtreetech.jscheduler.business.Master - Starting remote control interface...
2015-03-26 16:43:11,118 [main] DEBUG com.softtreetech.jscheduler.business.Master - Starting timer...
2015-03-26 16:43:11,134 [main] DEBUG com.softtreetech.jscheduler.business.Master - initialization completed...
2015-03-26 16:43:11,134 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading preferences
2015-03-26 16:43:11,134 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : Setting shutdown event hook
2015-03-26 16:43:11,134 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating user interface...
2015-03-26 16:43:12,681 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): totalRecordsNumber: 477
2015-03-26 16:43:12,681 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): deletedRecordsNumber: 30
2015-03-26 16:43:14,056 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : ready to run jobs
2015-03-26 16:43:14,290 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : end
2015-03-26 16:43:14,290 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : end
2015-03-26 16:44:08,259 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.ui.dialogs.jobwizard.JobWizard - show() : start
2015-03-26 16:44:10,114 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.ui.dialogs.jobwizard.JobWizard - show() : end
2015-03-26 16:45:01,848 [Thread-3] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:45:02,129 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - runJob(): start
2015-03-26 16:45:02,129 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - execProcess(): command line [sasbatch.bat "E:\Programs\Management\SASProgs\ScratchTracker.sas" /a] in work directory [null]
2015-03-26 16:45:02,129 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - Process will be launched in the current user session.
2015-03-26 16:45:02,129 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - waitForProcess(): start
2015-03-26 16:45:02,129 [Thread-111] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): start
2015-03-26 16:45:02,129 [Thread-111] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): sleeping. Max wait time is 600000 milliseconds
2015-03-26 16:45:03,692 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - waitForProcess(): end
2015-03-26 16:45:03,692 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : exit code: 0
2015-03-26 16:45:03,692 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : output:
2015-03-26 16:45:03,692 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - killProcess start
2015-03-26 16:45:03,692 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - runJob(): end
2015-03-26 16:45:03,692 [Thread-111] INFO  com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run():
java.lang.InterruptedException: sleep interrupted
   at java.lang.Thread.sleep(Native Method)
   at com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier.run(Unknown Source)
2015-03-26 16:45:03,692 [Thread-111] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): ended
2015-03-26 16:45:03,707 [Job #565 - Management Misc ScratchTracker] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:45:03,832 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - runJob(): start
2015-03-26 16:45:03,832 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - execProcess(): command line [sasbatch.bat "E:\Programs\Management\SASProgs\WMITracker.sas" /a] in work directory [null]
2015-03-26 16:45:03,832 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - Process will be launched in the current user session.
2015-03-26 16:45:03,832 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - waitForProcess(): start
2015-03-26 16:45:03,864 [Thread-116] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): start
2015-03-26 16:45:03,864 [Thread-116] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): sleeping. Max wait time is 600000 milliseconds
2015-03-26 16:45:05,864 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - waitForProcess(): end
2015-03-26 16:45:05,864 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : exit code: 0
2015-03-26 16:45:05,864 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : output:
2015-03-26 16:45:05,864 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - killProcess start
2015-03-26 16:45:05,864 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.runner.ProgramJobRunner - runJob(): end
2015-03-26 16:45:05,864 [Thread-116] INFO  com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run():
java.lang.InterruptedException: sleep interrupted
   at java.lang.Thread.sleep(Native Method)
   at com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier.run(Unknown Source)
2015-03-26 16:45:05,864 [Thread-116] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): ended
2015-03-26 16:45:05,910 [Job #827 - Management Misc WMITracker] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:45:06,035 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob (JobPropertiesWorkFile):
2015-03-26 16:45:06,051 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): jid=3269527; workfile created
2015-03-26 16:45:06,051 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): start
2015-03-26 16:45:06,051 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - execProcess(): command line [C:\24x7\jre7\bin\java.exe -jar jscheduler.jar /JOBFILE 3269527]; work directory [C:\24x7\]
2015-03-26 16:45:06,051 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - Process will be launched in the current user session.
2015-03-26 16:45:06,067 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): start
2015-03-26 16:45:06,067 [Thread-121] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): start
2015-03-26 16:45:06,067 [Thread-121] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): sleeping. Max wait time is 60000 milliseconds
2015-03-26 16:45:06,442 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - JVM version: 1.7.0_60-b19
2015-03-26 16:45:06,457 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - Current time with timezone: Thu Mar 26 16:45:06 NZDT 2015
2015-03-26 16:45:06,457 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : start
2015-03-26 16:45:06,457 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : job on demand: runtime id=3269527
2015-03-26 16:45:06,457 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : start
2015-03-26 16:45:06,457 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating business objects
2015-03-26 16:45:06,707 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating UI controller
2015-03-26 16:45:06,832 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : initializing business objects
2015-03-26 16:45:08,629 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading preferences
2015-03-26 16:45:08,629 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : Setting shutdown event hook
2015-03-26 16:45:08,629 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading job db
2015-03-26 16:45:08,692 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): totalRecordsNumber: 477
2015-03-26 16:45:08,692 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): deletedRecordsNumber: 30
2015-03-26 16:45:08,832 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: loading workfile
2015-03-26 16:45:08,848 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: deleting workfile
2015-03-26 16:45:08,848 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: running
2015-03-26 16:45:08,864 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script: // dummy job
2015-03-26 16:45:08,864 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): starting JavaScript
2015-03-26 16:45:08,864 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job not using user authentication
2015-03-26 16:45:08,895 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): starting jsthread
2015-03-26 16:45:08,895 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): waiting
2015-03-26 16:45:08,895 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread started
2015-03-26 16:45:09,098 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptRunner - runScript: JavaScriptRunner
2015-03-26 16:45:09,129 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread stopped
2015-03-26 16:45:09,129 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script evaluated
2015-03-26 16:45:09,145 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): stopping local js thread
2015-03-26 16:45:09,145 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - stopJScript()
2015-03-26 16:45:09,145 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job finished
2015-03-26 16:45:09,192 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: completed
2015-03-26 16:45:09,192 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() start
2015-03-26 16:45:09,192 [Thread-1] DEBUG com.softtreetech.jscheduler.business.runner.JobExecutorImpl - killAll() start
2015-03-26 16:45:09,192 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() end
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): end
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : exit code: 0
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : output: Copyright (c) 2004-2013 SoftTree Technologies, Inc.
Detached job started.

Press Ctrl+C to abort


2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - killProcess start
2015-03-26 16:45:09,207 [Thread-121] INFO  com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run():
java.lang.InterruptedException: sleep interrupted
   at java.lang.Thread.sleep(Native Method)
   at com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier.run(Unknown Source)
2015-03-26 16:45:09,207 [Thread-121] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): ended
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - stopExecutionImpl(): jid=3269527; workfile deleted
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): exit code: 0
2015-03-26 16:45:09,207 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): end
2015-03-26 16:45:09,254 [Job #666 - ETL—StaffSharePoint Hourly Start] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:45:09,379 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob (JobPropertiesWorkFile):
2015-03-26 16:45:09,379 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): jid=3269528; workfile created
2015-03-26 16:45:09,379 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): start
2015-03-26 16:45:09,379 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - execProcess(): command line [C:\24x7\jre7\bin\java.exe -jar jscheduler.jar /JOBFILE 3269528]; work directory [C:\24x7\]
2015-03-26 16:45:09,379 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - Process will be launched in the current user session.
2015-03-26 16:45:09,395 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): start
2015-03-26 16:45:09,395 [Thread-128] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): start
2015-03-26 16:45:09,395 [Thread-128] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): sleeping. Max wait time is 60000 milliseconds
2015-03-26 16:45:09,785 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - JVM version: 1.7.0_60-b19
2015-03-26 16:45:09,785 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - Current time with timezone: Thu Mar 26 16:45:09 NZDT 2015
2015-03-26 16:45:09,785 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : start
2015-03-26 16:45:09,785 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : job on demand: runtime id=3269528
2015-03-26 16:45:09,785 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : start
2015-03-26 16:45:09,801 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating business objects
2015-03-26 16:45:10,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating UI controller
2015-03-26 16:45:10,176 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : initializing business objects
2015-03-26 16:45:11,910 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading preferences
2015-03-26 16:45:11,910 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : Setting shutdown event hook
2015-03-26 16:45:11,926 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading job db
2015-03-26 16:45:11,973 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): totalRecordsNumber: 477
2015-03-26 16:45:11,973 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): deletedRecordsNumber: 30
2015-03-26 16:45:12,129 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: loading workfile
2015-03-26 16:45:12,145 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: deleting workfile
2015-03-26 16:45:12,145 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: running
2015-03-26 16:45:12,160 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script: // dummy job
2015-03-26 16:45:12,160 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): starting JavaScript
2015-03-26 16:45:12,160 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job not using user authentication
2015-03-26 16:45:12,192 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): starting jsthread
2015-03-26 16:45:12,192 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): waiting
2015-03-26 16:45:12,207 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread started
2015-03-26 16:45:12,410 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptRunner - runScript: JavaScriptRunner
2015-03-26 16:45:12,442 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread stopped
2015-03-26 16:45:12,442 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script evaluated
2015-03-26 16:45:12,442 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): stopping local js thread
2015-03-26 16:45:12,442 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - stopJScript()
2015-03-26 16:45:12,442 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job finished
2015-03-26 16:45:12,489 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: completed
2015-03-26 16:45:12,489 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() start
2015-03-26 16:45:12,489 [Thread-1] DEBUG com.softtreetech.jscheduler.business.runner.JobExecutorImpl - killAll() start
2015-03-26 16:45:12,489 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() end
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): end
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : exit code: 0
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : output: Copyright (c) 2004-2013 SoftTree Technologies, Inc.
Detached job started.

Press Ctrl+C to abort


2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - killProcess start
2015-03-26 16:45:12,504 [Thread-128] INFO  com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run():
java.lang.InterruptedException: sleep interrupted
   at java.lang.Thread.sleep(Native Method)
   at com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier.run(Unknown Source)
2015-03-26 16:45:12,504 [Thread-128] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): ended
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - stopExecutionImpl(): jid=3269528; workfile deleted
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): exit code: 0
2015-03-26 16:45:12,504 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): end
2015-03-26 16:45:12,535 [Job #790 - ETL—Zeald Hourly Start] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:45:12,660 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob (JobPropertiesWorkFile):
2015-03-26 16:45:12,660 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): jid=3269529; workfile created
2015-03-26 16:45:12,660 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): start
2015-03-26 16:45:12,660 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - execProcess(): command line [C:\24x7\jre7\bin\java.exe -jar jscheduler.jar /JOBFILE 3269529]; work directory [C:\24x7\]
2015-03-26 16:45:12,660 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - Process will be launched in the current user session.
2015-03-26 16:45:12,676 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): start
2015-03-26 16:45:12,676 [Thread-133] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): start
2015-03-26 16:45:12,676 [Thread-133] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): sleeping. Max wait time is 60000 milliseconds
2015-03-26 16:45:13,035 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - JVM version: 1.7.0_60-b19
2015-03-26 16:45:13,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - Current time with timezone: Thu Mar 26 16:45:13 NZDT 2015
2015-03-26 16:45:13,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : start
2015-03-26 16:45:13,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - main(...) : job on demand: runtime id=3269529
2015-03-26 16:45:13,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : start
2015-03-26 16:45:13,051 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating business objects
2015-03-26 16:45:13,285 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : creating UI controller
2015-03-26 16:45:13,395 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : initializing business objects
2015-03-26 16:45:14,989 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading preferences
2015-03-26 16:45:14,989 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : Setting shutdown event hook
2015-03-26 16:45:14,989 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : loading job db
2015-03-26 16:45:15,035 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): totalRecordsNumber: 477
2015-03-26 16:45:15,035 [main] DEBUG com.softtreetech.jscheduler.db.drivers.file.FileJobDbStorage - buildIndex(): deletedRecordsNumber: 30
2015-03-26 16:45:15,160 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: loading workfile
2015-03-26 16:45:15,160 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: deleting workfile
2015-03-26 16:45:15,160 [main] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: running
2015-03-26 16:45:15,176 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script: // dummy job
2015-03-26 16:45:15,176 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): starting JavaScript
2015-03-26 16:45:15,176 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job not using user authentication
2015-03-26 16:45:15,207 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): starting jsthread
2015-03-26 16:45:15,207 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - evaluateScript(): waiting
2015-03-26 16:45:15,207 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread started
2015-03-26 16:45:15,364 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptRunner - runScript: JavaScriptRunner
2015-03-26 16:45:15,379 [JavaScript] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - jsthread stopped
2015-03-26 16:45:15,379 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): script evaluated
2015-03-26 16:45:15,379 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): stopping local js thread
2015-03-26 16:45:15,379 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.javascript.JavaScriptThreadImpl - stopJScript()
2015-03-26 16:45:15,379 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.JavaScriptJobRunner - runJob(): job finished
2015-03-26 16:45:15,426 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.JSchedulerStarter - startup() : detached job: completed
2015-03-26 16:45:15,426 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() start
2015-03-26 16:45:15,426 [Thread-1] DEBUG com.softtreetech.jscheduler.business.runner.JobExecutorImpl - killAll() start
2015-03-26 16:45:15,426 [Thread-1] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() end
2015-03-26 16:45:15,426 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - waitForProcess(): end
2015-03-26 16:45:15,426 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : exit code: 0
2015-03-26 16:45:15,426 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner - isFailed(...) : output: Copyright (c) 2004-2013 SoftTree Technologies, Inc.
Detached job started.

Press Ctrl+C to abort


2015-03-26 16:45:15,426 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - killProcess start
2015-03-26 16:45:15,442 [Thread-133] INFO  com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run():
java.lang.InterruptedException: sleep interrupted
   at java.lang.Thread.sleep(Native Method)
   at com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier.run(Unknown Source)
2015-03-26 16:45:15,442 [Thread-133] DEBUG com.softtreetech.jscheduler.business.runner.AbstractJobRunner$TimeoutVerifier - run(): ended
2015-03-26 16:45:15,442 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - stopExecutionImpl(): jid=3269529; workfile deleted
2015-03-26 16:45:15,442 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): exit code: 0
2015-03-26 16:45:15,442 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.runner.DetachedJobRunner - runJob(): end
2015-03-26 16:45:15,473 [Job #378 - ETL—FormsServer Hourly Start] DEBUG com.softtreetech.jscheduler.business.queue.JobQueue - QUEUE_UNLOCKED
2015-03-26 16:46:26,566 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() start
2015-03-26 16:46:26,894 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.business.runner.JobExecutorImpl - killAll() start
2015-03-26 16:46:26,894 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.business.dde.DDEServer - Stopping DDE service...
2015-03-26 16:46:26,894 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.business.dde.DDEServer - DDE service stopped
2015-03-26 16:46:26,910 [AWT-EventQueue-0] DEBUG com.softtreetech.jscheduler.business.Master - shutdown() end


Windows 8.1 Pro.
Thu Mar 26, 2015 1:33 am View user's profile Send private message
Display posts from previous:    
Reply to topic    SoftTree Technologies Forum Index » 24x7 Scheduler, Event Server, Automation Suite All times are GMT - 4 Hours
Page 1 of 1

 
Jump to: 
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum


 

 

Powered by phpBB © 2001, 2005 phpBB Group
Design by Freestyle XL / Flowers Online.