Log opened at Thu, Apr 04 2019 19:11:44 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-04-05 02:00:20.1: ++ Weather Safe ++
2019-04-05 02:13:11.9: ++ Observatory Startup ++
2019-04-05 02:13:11.9: Start ACP Sequencer's StartupObs script
2019-04-05 02:13:11.9: ACP.RunScript(StartupObs, ):
2019-04-05 02:13:11.9:   Script ShutdownObs is loaded, and
2019-04-05 02:13:11.9:   the console is idle.
2019-04-05 02:13:11.9:   In start loop, i = 0
2019-04-05 02:13:11.9:   Console is now idle
2019-04-05 02:13:11.9:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2019-04-05 02:13:11.9:   Script started successfully
2019-04-05 02:13:21.9: Sequencer is now active
2019-04-05 02:14:19.9: Sequencer is no longer active
2019-04-05 02:14:21.1: Open the observatory dome/roof
2019-04-05 02:15:46.1: Observatory startup succeeded, and dome opened successfully
2019-04-05 02:23:01.1: ++ Auto Focus ++
2019-04-05 02:23:01.2: Doing initial autofocus.
2019-04-05 02:23:01.2: Start special ACP AutoFocus script for scheduler
2019-04-05 02:23:01.2: ACP.RunScript(AutoFocusScheduler, ):
2019-04-05 02:23:01.2:   Script StartupObs is loaded, and
2019-04-05 02:23:01.2:   the console is idle.
2019-04-05 02:23:01.2:   In start loop, i = 0
2019-04-05 02:23:01.2:   Console is now idle
2019-04-05 02:23:01.2:   Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-04-05 02:23:01.2:   Script started successfully
2019-04-05 02:23:11.2: Sequencer is now active
2019-04-05 02:27:17.6: Sequencer is no longer active
2019-04-05 02:27:17.6: Next periodic autofocus ASAP after 05-Apr-2019 05:26:50 UTC
2019-04-05 02:27:18.2: SelectRunning: No running plans
2019-04-05 02:27:18.3: EPH (136472Makemake): type=Minor Planet time=02:27:18 ra=13.0742458 dec=24.406396
2019-04-05 02:27:18.3: Obs Jup in Plan Jup was vetoed by Horizon constraint.
2019-04-05 02:27:18.3:     Horizon estimates ready after 05-Apr-2019 09:22:16 UTC
2019-04-05 02:27:18.3: EPH (Jupiter): type=Major Planet time=02:27:18 ra=17.5689053 dec=-22.669477
2019-04-05 02:27:18.3: Obs Jupiter in Plan Jupiter was vetoed by Horizon constraint.
2019-04-05 02:27:18.3:     Horizon estimates ready after 05-Apr-2019 09:22:20 UTC
2019-04-05 02:27:18.4: Efficiency: Selecting from 5 pending plans:
2019-04-05 02:27:18.5:   Selected Plan delOri (TotEff=1.49)
2019-04-05 02:27:18.5: Dispatcher cycle time: 0.4 sec.
2019-04-05 02:27:18.5: Acquire data for Observation delOri...
2019-04-05 02:27:18.5:   (belongs to Project ASTR402_Spring2019, Plan delOri)
2019-04-05 02:27:18.5: Doing 60 sets
2019-04-05 02:27:19.1: Send Observation delOri to ACP Sequencer
2019-04-05 02:27:19.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:27:19.1:   Script AutoFocusScheduler is loaded, and
2019-04-05 02:27:19.1:   the console is idle.
2019-04-05 02:27:19.1:   In start loop, i = 0
2019-04-05 02:27:19.1:   Console is now idle
2019-04-05 02:27:19.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:27:19.1:   Script started successfully
2019-04-05 02:27:29.1: Sequencer is now active
2019-04-05 02:31:34.2: Sequencer is no longer active
2019-04-05 02:31:34.2: Post-job status check done (stat=Completed)
2019-04-05 02:31:34.2: Send Observation delOri to ACP Sequencer
2019-04-05 02:31:34.2: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:31:34.2:   Script AcquireScheduler is loaded, and
2019-04-05 02:31:34.2:   the console is idle.
2019-04-05 02:31:34.2:   In start loop, i = 0
2019-04-05 02:31:34.2:   Console is now idle
2019-04-05 02:31:34.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:31:34.5:   Script started successfully
2019-04-05 02:31:44.5: Sequencer is now active
2019-04-05 02:33:54.5: Sequencer is no longer active
2019-04-05 02:33:54.5: Post-job status check done (stat=Completed)
2019-04-05 02:33:54.5: Send Observation delOri to ACP Sequencer
2019-04-05 02:33:54.5: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:33:54.5:   Script AcquireScheduler is loaded, and
2019-04-05 02:33:54.5:   the console is idle.
2019-04-05 02:33:54.5:   In start loop, i = 0
2019-04-05 02:33:54.5:   Console is now idle
2019-04-05 02:33:54.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:33:54.9:   Script started successfully
2019-04-05 02:34:04.9: Sequencer is now active
2019-04-05 02:36:17.0: Sequencer is no longer active
2019-04-05 02:36:17.0: Post-job status check done (stat=Completed)
2019-04-05 02:36:17.0: Send Observation delOri to ACP Sequencer
2019-04-05 02:36:17.0: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:36:17.0:   Script AcquireScheduler is loaded, and
2019-04-05 02:36:17.0:   the console is idle.
2019-04-05 02:36:17.0:   In start loop, i = 0
2019-04-05 02:36:17.0:   Console is now idle
2019-04-05 02:36:17.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:36:17.3:   Script started successfully
2019-04-05 02:36:27.3: Sequencer is now active
2019-04-05 02:38:42.1: Sequencer is no longer active
2019-04-05 02:38:42.1: Post-job status check done (stat=Completed)
2019-04-05 02:38:42.1: Send Observation delOri to ACP Sequencer
2019-04-05 02:38:42.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:38:42.1:   Script AcquireScheduler is loaded, and
2019-04-05 02:38:42.1:   the console is idle.
2019-04-05 02:38:42.1:   In start loop, i = 0
2019-04-05 02:38:42.1:   Console is now idle
2019-04-05 02:38:42.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:38:42.5:   Script started successfully
2019-04-05 02:38:52.5: Sequencer is now active
2019-04-05 02:41:02.6: Sequencer is no longer active
2019-04-05 02:41:02.6: Post-job status check done (stat=Completed)
2019-04-05 02:41:02.7: Send Observation delOri to ACP Sequencer
2019-04-05 02:41:02.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:41:02.7:   Script AcquireScheduler is loaded, and
2019-04-05 02:41:02.7:   the console is idle.
2019-04-05 02:41:02.7:   In start loop, i = 0
2019-04-05 02:41:02.7:   Console is now idle
2019-04-05 02:41:03.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:41:03.0:   Script started successfully
2019-04-05 02:41:13.0: Sequencer is now active
2019-04-05 02:43:23.1: Sequencer is no longer active
2019-04-05 02:43:23.1: Post-job status check done (stat=Completed)
2019-04-05 02:43:23.1: Send Observation delOri to ACP Sequencer
2019-04-05 02:43:23.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:43:23.1:   Script AcquireScheduler is loaded, and
2019-04-05 02:43:23.1:   the console is idle.
2019-04-05 02:43:23.1:   In start loop, i = 0
2019-04-05 02:43:23.1:   Console is now idle
2019-04-05 02:43:23.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:43:23.4:   Script started successfully
2019-04-05 02:43:33.4: Sequencer is now active
2019-04-05 02:45:45.6: Sequencer is no longer active
2019-04-05 02:45:45.6: Post-job status check done (stat=Completed)
2019-04-05 02:45:45.7: Send Observation delOri to ACP Sequencer
2019-04-05 02:45:45.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:45:45.7:   Script AcquireScheduler is loaded, and
2019-04-05 02:45:45.7:   the console is idle.
2019-04-05 02:45:45.7:   In start loop, i = 0
2019-04-05 02:45:45.7:   Console is now idle
2019-04-05 02:45:46.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:45:46.0:   Script started successfully
2019-04-05 02:45:56.0: Sequencer is now active
2019-04-05 02:48:01.0: Sequencer is no longer active
2019-04-05 02:48:01.0: Post-job status check done (stat=Completed)
2019-04-05 02:48:01.0: Send Observation delOri to ACP Sequencer
2019-04-05 02:48:01.0: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:48:01.0:   Script AcquireScheduler is loaded, and
2019-04-05 02:48:01.0:   the console is idle.
2019-04-05 02:48:01.0:   In start loop, i = 0
2019-04-05 02:48:01.0:   Console is now idle
2019-04-05 02:48:01.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:48:01.4:   Script started successfully
2019-04-05 02:48:11.4: Sequencer is now active
2019-04-05 02:50:22.7: Sequencer is no longer active
2019-04-05 02:50:22.7: Post-job status check done (stat=Completed)
2019-04-05 02:50:22.7: Send Observation delOri to ACP Sequencer
2019-04-05 02:50:22.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:50:22.7:   Script AcquireScheduler is loaded, and
2019-04-05 02:50:22.7:   the console is idle.
2019-04-05 02:50:22.7:   In start loop, i = 0
2019-04-05 02:50:22.7:   Console is now idle
2019-04-05 02:50:23.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:50:23.0:   Script started successfully
2019-04-05 02:50:33.0: Sequencer is now active
2019-04-05 02:52:45.1: Sequencer is no longer active
2019-04-05 02:52:45.1: Post-job status check done (stat=Completed)
2019-04-05 02:52:45.1: Send Observation delOri to ACP Sequencer
2019-04-05 02:52:45.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:52:45.1:   Script AcquireScheduler is loaded, and
2019-04-05 02:52:45.1:   the console is idle.
2019-04-05 02:52:45.1:   In start loop, i = 0
2019-04-05 02:52:45.1:   Console is now idle
2019-04-05 02:52:45.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:52:45.4:   Script started successfully
2019-04-05 02:52:55.4: Sequencer is now active
2019-04-05 02:55:03.5: Sequencer is no longer active
2019-04-05 02:55:03.5: Post-job status check done (stat=Completed)
2019-04-05 02:55:03.6: Send Observation delOri to ACP Sequencer
2019-04-05 02:55:03.6: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:55:03.6:   Script AcquireScheduler is loaded, and
2019-04-05 02:55:03.6:   the console is idle.
2019-04-05 02:55:03.6:   In start loop, i = 0
2019-04-05 02:55:03.6:   Console is now idle
2019-04-05 02:55:03.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:55:03.9:   Script started successfully
2019-04-05 02:55:13.9: Sequencer is now active
2019-04-05 02:57:29.4: Sequencer is no longer active
2019-04-05 02:57:29.4: Post-job status check done (stat=Completed)
2019-04-05 02:57:29.4: Send Observation delOri to ACP Sequencer
2019-04-05 02:57:29.4: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:57:29.4:   Script AcquireScheduler is loaded, and
2019-04-05 02:57:29.4:   the console is idle.
2019-04-05 02:57:29.4:   In start loop, i = 0
2019-04-05 02:57:29.4:   Console is now idle
2019-04-05 02:57:29.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:57:29.8:   Script started successfully
2019-04-05 02:57:39.8: Sequencer is now active
2019-04-05 02:59:51.1: Sequencer is no longer active
2019-04-05 02:59:51.1: Post-job status check done (stat=Completed)
2019-04-05 02:59:51.1: Send Observation delOri to ACP Sequencer
2019-04-05 02:59:51.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 02:59:51.1:   Script AcquireScheduler is loaded, and
2019-04-05 02:59:51.1:   the console is idle.
2019-04-05 02:59:51.1:   In start loop, i = 0
2019-04-05 02:59:51.1:   Console is now idle
2019-04-05 02:59:51.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 02:59:51.5:   Script started successfully
2019-04-05 03:00:01.5: Sequencer is now active
2019-04-05 03:02:13.4: Sequencer is no longer active
2019-04-05 03:02:13.4: Post-job status check done (stat=Completed)
2019-04-05 03:02:13.5: Send Observation delOri to ACP Sequencer
2019-04-05 03:02:13.5: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:02:13.5:   Script AcquireScheduler is loaded, and
2019-04-05 03:02:13.5:   the console is idle.
2019-04-05 03:02:13.5:   In start loop, i = 0
2019-04-05 03:02:13.5:   Console is now idle
2019-04-05 03:02:13.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:02:13.8:   Script started successfully
2019-04-05 03:02:23.8: Sequencer is now active
2019-04-05 03:04:29.7: Sequencer is no longer active
2019-04-05 03:04:29.7: Post-job status check done (stat=Completed)
2019-04-05 03:04:29.7: Send Observation delOri to ACP Sequencer
2019-04-05 03:04:29.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:04:29.7:   Script AcquireScheduler is loaded, and
2019-04-05 03:04:29.7:   the console is idle.
2019-04-05 03:04:29.7:   In start loop, i = 0
2019-04-05 03:04:29.7:   Console is now idle
2019-04-05 03:04:30.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:04:30.0:   Script started successfully
2019-04-05 03:04:40.0: Sequencer is now active
2019-04-05 03:06:39.4: Dispatcher stopped at 05-Apr-2019 03:06:39 UTC
2019-04-05 03:06:39.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-04-05 03:06:46.8: Sequencer is no longer active
2019-04-05 03:06:46.8: ** ACP script "AcquireScheduler" failed:
2019-04-05 03:06:46.8: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 2418 column 5.

2019-04-05 03:06:49.8: Post-job status check done (stat=Running)
2019-04-05 03:06:49.8: RUN FAILED: Scheduler was stopped during run
2019-04-05 03:06:49.8: ACP data acquisition failed for Observation delOri.
2019-04-05 03:06:49.8:   (Observation delOri interrupted by operator or system.)
2019-04-05 03:11:41.4: Dispatcher started at 05-Apr-2019 03:11:41 UTC
2019-04-05 03:11:41.4:     Priority:          W[0]=1.00
2019-04-05 03:11:41.4:     Transit Altitude:  W[1]=0.70
2019-04-05 03:11:41.4:     Highest Altitude:  W[6]=0.00
2019-04-05 03:11:41.4:     Lateness:          W[5]=0.00
2019-04-05 03:11:41.4:     Slew Distance:     W[2]=0.30
2019-04-05 03:11:41.4:     Retry Count:       W[3]=0.00
2019-04-05 03:11:41.4:     Meridian Crossing: W[4]=0.00
2019-04-05 03:11:41.4:     Obs Conditions:    W[7]=0.40
2019-04-05 03:11:41.4:     Rising Plan Delay: disabled
2019-04-05 03:11:41.5: ++ Observatory Startup ++
2019-04-05 03:11:41.5: Start ACP Sequencer's StartupObs script
2019-04-05 03:11:41.8: Starting with open dome, unsafe weather will cause operator intervention error
2019-04-05 03:11:41.8: ACP.RunScript(StartupObs, ):
2019-04-05 03:11:41.8:   Script AcquireScheduler is loaded, and
2019-04-05 03:11:41.8:   the console is idle.
2019-04-05 03:11:41.8:   In start loop, i = 0
2019-04-05 03:11:41.8:   Console is now idle
2019-04-05 03:11:41.8:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2019-04-05 03:11:41.8:   Script started successfully
2019-04-05 03:11:51.8: Sequencer is now active
2019-04-05 03:12:03.1: Sequencer is no longer active
2019-04-05 03:12:03.4: Observatory startup succeeded
2019-04-05 03:12:03.6: ++ Auto Focus ++
2019-04-05 03:12:03.6: Doing initial autofocus.
2019-04-05 03:12:03.6: Start special ACP AutoFocus script for scheduler
2019-04-05 03:12:03.6: ACP.RunScript(AutoFocusScheduler, ):
2019-04-05 03:12:03.6:   Script StartupObs is loaded, and
2019-04-05 03:12:03.6:   the console is idle.
2019-04-05 03:12:03.6:   In start loop, i = 0
2019-04-05 03:12:03.6:   Console is now idle
2019-04-05 03:12:03.6:   Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-04-05 03:12:03.6:   Script started successfully
2019-04-05 03:12:13.6: Sequencer is now active
2019-04-05 03:15:23.3: Sequencer is no longer active
2019-04-05 03:15:23.3: Next periodic autofocus ASAP after 05-Apr-2019 06:14:57 UTC
2019-04-05 03:15:24.3: SelectRunning: No running plans
2019-04-05 03:15:24.3: EPH (136472Makemake): type=Minor Planet time=03:15:24 ra=13.0742078 dec=24.406580
2019-04-05 03:15:24.4: Efficiency: Selecting from 4 pending plans:
2019-04-05 03:15:24.4:   Selected Plan WD1145+017 (TotEff=1.47)
2019-04-05 03:15:24.5: Dispatcher cycle time: 0.6 sec.
2019-04-05 03:15:24.5: Acquire data for Observation WD1145+017...
2019-04-05 03:15:24.5:   (belongs to Project ASTR402_Spring2019, Plan WD1145+017)
2019-04-05 03:15:24.5: Doing 60 sets
2019-04-05 03:15:24.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:15:24.6: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:15:24.6:   Script AutoFocusScheduler is loaded, and
2019-04-05 03:15:24.6:   the console is idle.
2019-04-05 03:15:24.6:   In start loop, i = 0
2019-04-05 03:15:24.6:   Console is now idle
2019-04-05 03:15:24.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:15:24.7:   Script started successfully
2019-04-05 03:15:34.7: Sequencer is now active
2019-04-05 03:25:52.7: Sequencer is no longer active
2019-04-05 03:25:52.7: Post-job status check done (stat=Completed)
2019-04-05 03:25:52.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:25:52.8: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:25:52.8:   Script AcquireScheduler is loaded, and
2019-04-05 03:25:52.8:   the console is idle.
2019-04-05 03:25:52.8:   In start loop, i = 0
2019-04-05 03:25:52.8:   Console is now idle
2019-04-05 03:25:53.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:25:53.1:   Script started successfully
2019-04-05 03:26:03.1: Sequencer is now active
2019-04-05 03:31:49.1: Sequencer is no longer active
2019-04-05 03:31:49.1: Post-job status check done (stat=Completed)
2019-04-05 03:31:49.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:31:49.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:31:49.1:   Script AcquireScheduler is loaded, and
2019-04-05 03:31:49.1:   the console is idle.
2019-04-05 03:31:49.1:   In start loop, i = 0
2019-04-05 03:31:49.1:   Console is now idle
2019-04-05 03:31:49.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:31:49.5:   Script started successfully
2019-04-05 03:31:59.5: Sequencer is now active
2019-04-05 03:37:52.3: Sequencer is no longer active
2019-04-05 03:37:52.3: Post-job status check done (stat=Completed)
2019-04-05 03:37:52.3: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:37:52.3: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:37:52.3:   Script AcquireScheduler is loaded, and
2019-04-05 03:37:52.3:   the console is idle.
2019-04-05 03:37:52.3:   In start loop, i = 0
2019-04-05 03:37:52.3:   Console is now idle
2019-04-05 03:37:52.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:37:52.7:   Script started successfully
2019-04-05 03:38:02.7: Sequencer is now active
2019-04-05 03:43:52.9: Sequencer is no longer active
2019-04-05 03:43:52.9: Post-job status check done (stat=Completed)
2019-04-05 03:43:52.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:43:52.9: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:43:52.9:   Script AcquireScheduler is loaded, and
2019-04-05 03:43:52.9:   the console is idle.
2019-04-05 03:43:52.9:   In start loop, i = 0
2019-04-05 03:43:52.9:   Console is now idle
2019-04-05 03:43:53.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:43:53.2:   Script started successfully
2019-04-05 03:44:03.2: Sequencer is now active
2019-04-05 03:49:51.1: Sequencer is no longer active
2019-04-05 03:49:51.1: Post-job status check done (stat=Completed)
2019-04-05 03:49:51.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:49:51.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:49:51.1:   Script AcquireScheduler is loaded, and
2019-04-05 03:49:51.1:   the console is idle.
2019-04-05 03:49:51.1:   In start loop, i = 0
2019-04-05 03:49:51.1:   Console is now idle
2019-04-05 03:49:51.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:49:51.5:   Script started successfully
2019-04-05 03:50:01.5: Sequencer is now active
2019-04-05 03:55:50.3: Sequencer is no longer active
2019-04-05 03:55:50.3: Post-job status check done (stat=Completed)
2019-04-05 03:55:50.3: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 03:55:50.3: ACP.RunScript(AcquireScheduler, ):
2019-04-05 03:55:50.3:   Script AcquireScheduler is loaded, and
2019-04-05 03:55:50.3:   the console is idle.
2019-04-05 03:55:50.3:   In start loop, i = 0
2019-04-05 03:55:50.3:   Console is now idle
2019-04-05 03:55:50.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 03:55:50.7:   Script started successfully
2019-04-05 03:56:00.7: Sequencer is now active
2019-04-05 04:01:49.0: Sequencer is no longer active
2019-04-05 04:01:49.0: Post-job status check done (stat=Completed)
2019-04-05 04:01:49.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:01:49.0: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:01:49.0:   Script AcquireScheduler is loaded, and
2019-04-05 04:01:49.0:   the console is idle.
2019-04-05 04:01:49.0:   In start loop, i = 0
2019-04-05 04:01:49.0:   Console is now idle
2019-04-05 04:01:49.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:01:49.3:   Script started successfully
2019-04-05 04:01:59.3: Sequencer is now active
2019-04-05 04:07:47.1: Sequencer is no longer active
2019-04-05 04:07:47.1: Post-job status check done (stat=Completed)
2019-04-05 04:07:47.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:07:47.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:07:47.1:   Script AcquireScheduler is loaded, and
2019-04-05 04:07:47.1:   the console is idle.
2019-04-05 04:07:47.1:   In start loop, i = 0
2019-04-05 04:07:47.1:   Console is now idle
2019-04-05 04:07:47.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:07:47.5:   Script started successfully
2019-04-05 04:07:57.5: Sequencer is now active
2019-04-05 04:13:43.5: Sequencer is no longer active
2019-04-05 04:13:43.5: Post-job status check done (stat=Completed)
2019-04-05 04:13:43.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:13:43.5: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:13:43.5:   Script AcquireScheduler is loaded, and
2019-04-05 04:13:43.5:   the console is idle.
2019-04-05 04:13:43.5:   In start loop, i = 0
2019-04-05 04:13:43.5:   Console is now idle
2019-04-05 04:13:43.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:13:43.9:   Script started successfully
2019-04-05 04:13:53.9: Sequencer is now active
2019-04-05 04:19:44.1: Sequencer is no longer active
2019-04-05 04:19:44.1: Post-job status check done (stat=Completed)
2019-04-05 04:19:44.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:19:44.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:19:44.1:   Script AcquireScheduler is loaded, and
2019-04-05 04:19:44.1:   the console is idle.
2019-04-05 04:19:44.1:   In start loop, i = 0
2019-04-05 04:19:44.1:   Console is now idle
2019-04-05 04:19:44.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:19:44.5:   Script started successfully
2019-04-05 04:19:54.5: Sequencer is now active
2019-04-05 04:25:40.7: Sequencer is no longer active
2019-04-05 04:25:40.7: Post-job status check done (stat=Completed)
2019-04-05 04:25:40.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:25:40.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:25:40.7:   Script AcquireScheduler is loaded, and
2019-04-05 04:25:40.7:   the console is idle.
2019-04-05 04:25:40.7:   In start loop, i = 0
2019-04-05 04:25:40.7:   Console is now idle
2019-04-05 04:25:41.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:25:41.1:   Script started successfully
2019-04-05 04:25:51.1: Sequencer is now active
2019-04-05 04:31:41.2: Sequencer is no longer active
2019-04-05 04:31:41.2: Post-job status check done (stat=Completed)
2019-04-05 04:31:41.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:31:41.2: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:31:41.2:   Script AcquireScheduler is loaded, and
2019-04-05 04:31:41.2:   the console is idle.
2019-04-05 04:31:41.2:   In start loop, i = 0
2019-04-05 04:31:41.2:   Console is now idle
2019-04-05 04:31:41.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:31:41.6:   Script started successfully
2019-04-05 04:31:51.6: Sequencer is now active
2019-04-05 04:37:37.8: Sequencer is no longer active
2019-04-05 04:37:37.8: Post-job status check done (stat=Completed)
2019-04-05 04:37:37.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:37:37.8: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:37:37.8:   Script AcquireScheduler is loaded, and
2019-04-05 04:37:37.8:   the console is idle.
2019-04-05 04:37:37.8:   In start loop, i = 0
2019-04-05 04:37:37.8:   Console is now idle
2019-04-05 04:37:38.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:37:38.2:   Script started successfully
2019-04-05 04:37:48.2: Sequencer is now active
2019-04-05 04:43:34.5: Sequencer is no longer active
2019-04-05 04:43:34.5: Post-job status check done (stat=Completed)
2019-04-05 04:43:34.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:43:34.5: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:43:34.5:   Script AcquireScheduler is loaded, and
2019-04-05 04:43:34.5:   the console is idle.
2019-04-05 04:43:34.5:   In start loop, i = 0
2019-04-05 04:43:34.5:   Console is now idle
2019-04-05 04:43:34.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:43:34.8:   Script started successfully
2019-04-05 04:43:44.8: Sequencer is now active
2019-04-05 04:49:31.0: Sequencer is no longer active
2019-04-05 04:49:31.0: Post-job status check done (stat=Completed)
2019-04-05 04:49:31.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:49:31.0: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:49:31.0:   Script AcquireScheduler is loaded, and
2019-04-05 04:49:31.0:   the console is idle.
2019-04-05 04:49:31.0:   In start loop, i = 0
2019-04-05 04:49:31.0:   Console is now idle
2019-04-05 04:49:31.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:49:31.3:   Script started successfully
2019-04-05 04:49:41.3: Sequencer is now active
2019-04-05 04:55:29.4: Sequencer is no longer active
2019-04-05 04:55:29.4: Post-job status check done (stat=Completed)
2019-04-05 04:55:29.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 04:55:29.4: ACP.RunScript(AcquireScheduler, ):
2019-04-05 04:55:29.4:   Script AcquireScheduler is loaded, and
2019-04-05 04:55:29.4:   the console is idle.
2019-04-05 04:55:29.4:   In start loop, i = 0
2019-04-05 04:55:29.4:   Console is now idle
2019-04-05 04:55:29.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 04:55:29.7:   Script started successfully
2019-04-05 04:55:39.7: Sequencer is now active
2019-04-05 05:01:27.9: Sequencer is no longer active
2019-04-05 05:01:27.9: Post-job status check done (stat=Completed)
2019-04-05 05:01:27.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:01:27.9: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:01:27.9:   Script AcquireScheduler is loaded, and
2019-04-05 05:01:27.9:   the console is idle.
2019-04-05 05:01:27.9:   In start loop, i = 0
2019-04-05 05:01:27.9:   Console is now idle
2019-04-05 05:01:28.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:01:28.3:   Script started successfully
2019-04-05 05:01:38.3: Sequencer is now active
2019-04-05 05:07:26.7: Sequencer is no longer active
2019-04-05 05:07:26.7: Post-job status check done (stat=Completed)
2019-04-05 05:07:26.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:07:26.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:07:26.7:   Script AcquireScheduler is loaded, and
2019-04-05 05:07:26.7:   the console is idle.
2019-04-05 05:07:26.7:   In start loop, i = 0
2019-04-05 05:07:26.7:   Console is now idle
2019-04-05 05:07:27.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:07:27.0:   Script started successfully
2019-04-05 05:07:37.0: Sequencer is now active
2019-04-05 05:13:27.4: Sequencer is no longer active
2019-04-05 05:13:27.4: Post-job status check done (stat=Completed)
2019-04-05 05:13:27.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:13:27.4: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:13:27.4:   Script AcquireScheduler is loaded, and
2019-04-05 05:13:27.4:   the console is idle.
2019-04-05 05:13:27.4:   In start loop, i = 0
2019-04-05 05:13:27.4:   Console is now idle
2019-04-05 05:13:27.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:13:27.8:   Script started successfully
2019-04-05 05:13:37.8: Sequencer is now active
2019-04-05 05:19:26.1: Sequencer is no longer active
2019-04-05 05:19:26.1: Post-job status check done (stat=Completed)
2019-04-05 05:19:26.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:19:26.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:19:26.1:   Script AcquireScheduler is loaded, and
2019-04-05 05:19:26.1:   the console is idle.
2019-04-05 05:19:26.1:   In start loop, i = 0
2019-04-05 05:19:26.1:   Console is now idle
2019-04-05 05:19:26.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:19:26.4:   Script started successfully
2019-04-05 05:19:36.4: Sequencer is now active
2019-04-05 05:25:22.7: Sequencer is no longer active
2019-04-05 05:25:22.7: Post-job status check done (stat=Completed)
2019-04-05 05:25:22.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:25:22.7: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:25:22.7:   Script AcquireScheduler is loaded, and
2019-04-05 05:25:22.7:   the console is idle.
2019-04-05 05:25:22.7:   In start loop, i = 0
2019-04-05 05:25:22.7:   Console is now idle
2019-04-05 05:25:23.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:25:23.1:   Script started successfully
2019-04-05 05:25:33.1: Sequencer is now active
2019-04-05 05:31:21.3: Sequencer is no longer active
2019-04-05 05:31:21.3: Post-job status check done (stat=Completed)
2019-04-05 05:31:21.3: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:31:21.3: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:31:21.3:   Script AcquireScheduler is loaded, and
2019-04-05 05:31:21.3:   the console is idle.
2019-04-05 05:31:21.3:   In start loop, i = 0
2019-04-05 05:31:21.3:   Console is now idle
2019-04-05 05:31:21.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:31:21.7:   Script started successfully
2019-04-05 05:31:31.7: Sequencer is now active
2019-04-05 05:37:19.8: Sequencer is no longer active
2019-04-05 05:37:19.8: Post-job status check done (stat=Completed)
2019-04-05 05:37:19.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:37:19.8: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:37:19.8:   Script AcquireScheduler is loaded, and
2019-04-05 05:37:19.8:   the console is idle.
2019-04-05 05:37:19.8:   In start loop, i = 0
2019-04-05 05:37:19.8:   Console is now idle
2019-04-05 05:37:20.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:37:20.1:   Script started successfully
2019-04-05 05:37:30.1: Sequencer is now active
2019-04-05 05:43:29.0: Sequencer is no longer active
2019-04-05 05:43:29.0: Post-job status check done (stat=Completed)
2019-04-05 05:43:29.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:43:29.1: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:43:29.1:   Script AcquireScheduler is loaded, and
2019-04-05 05:43:29.1:   the console is idle.
2019-04-05 05:43:29.1:   In start loop, i = 0
2019-04-05 05:43:29.1:   Console is now idle
2019-04-05 05:43:29.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:43:29.4:   Script started successfully
2019-04-05 05:43:39.4: Sequencer is now active
2019-04-05 05:49:28.2: Sequencer is no longer active
2019-04-05 05:49:28.2: Post-job status check done (stat=Completed)
2019-04-05 05:49:28.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:49:28.2: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:49:28.2:   Script AcquireScheduler is loaded, and
2019-04-05 05:49:28.2:   the console is idle.
2019-04-05 05:49:28.2:   In start loop, i = 0
2019-04-05 05:49:28.2:   Console is now idle
2019-04-05 05:49:28.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:49:28.6:   Script started successfully
2019-04-05 05:49:38.6: Sequencer is now active
2019-04-05 05:55:26.8: Sequencer is no longer active
2019-04-05 05:55:26.8: Post-job status check done (stat=Completed)
2019-04-05 05:55:26.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 05:55:26.8: ACP.RunScript(AcquireScheduler, ):
2019-04-05 05:55:26.9:   Script AcquireScheduler is loaded, and
2019-04-05 05:55:26.9:   the console is idle.
2019-04-05 05:55:26.9:   In start loop, i = 0
2019-04-05 05:55:26.9:   Console is now idle
2019-04-05 05:55:27.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 05:55:27.2:   Script started successfully
2019-04-05 05:55:37.2: Sequencer is now active
2019-04-05 06:01:26.0: Sequencer is no longer active
2019-04-05 06:01:26.0: Post-job status check done (stat=Completed)
2019-04-05 06:01:26.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 06:01:26.0: ACP.RunScript(AcquireScheduler, ):
2019-04-05 06:01:26.0:   Script AcquireScheduler is loaded, and
2019-04-05 06:01:26.0:   the console is idle.
2019-04-05 06:01:26.0:   In start loop, i = 0
2019-04-05 06:01:26.0:   Console is now idle
2019-04-05 06:01:26.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 06:01:26.3:   Script started successfully
2019-04-05 06:01:36.3: Sequencer is now active
2019-04-05 06:07:23.4: Sequencer is no longer active
2019-04-05 06:07:23.4: Post-job status check done (stat=Completed)
2019-04-05 06:07:23.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 06:07:23.4: ACP.RunScript(AcquireScheduler, ):
2019-04-05 06:07:23.4:   Script AcquireScheduler is loaded, and
2019-04-05 06:07:23.4:   the console is idle.
2019-04-05 06:07:23.4:   In start loop, i = 0
2019-04-05 06:07:23.4:   Console is now idle
2019-04-05 06:07:23.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 06:07:23.7:   Script started successfully
2019-04-05 06:07:33.7: Sequencer is now active
2019-04-05 06:13:23.2: Sequencer is no longer active
2019-04-05 06:13:23.2: Post-job status check done (stat=Completed)
2019-04-05 06:13:23.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-05 06:13:23.2: ACP.RunScript(AcquireScheduler, ):
2019-04-05 06:13:23.2:   Script AcquireScheduler is loaded, and
2019-04-05 06:13:23.2:   the console is idle.
2019-04-05 06:13:23.2:   In start loop, i = 0
2019-04-05 06:13:23.2:   Console is now idle
2019-04-05 06:13:23.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-05 06:13:23.6:   Script started successfully
2019-04-05 06:13:33.6: Sequencer is now active
2019-04-05 06:13:38.6: Sequencer is no longer active
2019-04-05 06:13:41.6: Post-job status check done (stat=Running)
2019-04-05 06:13:41.6: ASSERT: ACP script exited with AcquireNow still True
2019-04-05 06:13:41.6: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-04-05 06:13:41.6: ACP data acquisition failed for Observation WD1145+017.
2019-04-05 06:13:41.6:   (Observation WD1145+017 interrupted by acquisition process failure.)
2019-04-05 06:13:41.8: **EXCEPTION IN SCHEDULER:
2019-04-05 06:13:41.8: DOME FAILURE: Dome reports shutter error status
2019-04-05 06:13:41.8: Traceback:
   at DC3.Scheduler.ACPSequencer.get_DomeOpen()
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2019-04-05 06:13:41.8: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2019-04-05 06:13:41.8: DOME FAILURE: Dome reports shutter error status when asked to close.
2019-04-05 06:13:41.8: No operator intervention command tool specified
2019-04-05 06:13:41.9: Run statistics:
2019-04-05 06:13:41.9:   Observations:   62
2019-04-05 06:13:41.9:   Considered:     16
2019-04-05 06:13:41.9:   Completed:      6
2019-04-05 06:13:41.9:   Skipped:        4
2019-04-05 06:13:41.9:   Never Eligible: 46
2019-04-05 06:13:41.9:   Failed:         6
2019-04-05 06:13:41.9: Shutter-Open efficiency: 60.87%
2019-04-05 06:13:41.9: Overall Efficiency: 99.90%
2019-04-05 06:13:41.9: Release ACP sequencer
Log closed at Fri, Apr 05 2019 06:13:43 UTC (actual time)