Log opened at Mon, Apr 08 2019 01:32:43 UTC (actual time)
Current log level is Brief
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
Log level changed from Brief to Verbose
Log level changed from Verbose to Debug
2019-04-08 01:46:04.3: Dispatcher stopped at 08-Apr-2019 01:46:04 UTC
2019-04-08 01:46:04.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-04-08 02:00:35.9: ++ Weather Safe ++
2019-04-08 05:50:44.1: Dispatcher started at 08-Apr-2019 05:50:44 UTC
2019-04-08 05:50:44.1:     Priority:          W[0]=1.00
2019-04-08 05:50:44.1:     Transit Altitude:  W[1]=0.70
2019-04-08 05:50:44.1:     Highest Altitude:  W[6]=0.00
2019-04-08 05:50:44.1:     Lateness:          W[5]=0.00
2019-04-08 05:50:44.1:     Slew Distance:     W[2]=0.30
2019-04-08 05:50:44.1:     Retry Count:       W[3]=0.00
2019-04-08 05:50:44.1:     Meridian Crossing: W[4]=0.00
2019-04-08 05:50:44.1:     Obs Conditions:    W[7]=0.40
2019-04-08 05:50:44.1:     Rising Plan Delay: disabled
2019-04-08 05:50:46.8: ++ Observatory Startup ++
2019-04-08 05:50:46.8: Start ACP Sequencer's StartupObs script
2019-04-08 05:50:46.8: Starting with open dome, unsafe weather will cause operator intervention error
2019-04-08 05:50:46.8: ACP.RunScript(StartupObs, ):
2019-04-08 05:50:46.8:   Script AcquireImages is loaded, and
2019-04-08 05:50:46.8:   the console is idle.
2019-04-08 05:50:46.8:   In start loop, i = 0
2019-04-08 05:50:46.8:   Console is now idle
2019-04-08 05:50:46.8:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2019-04-08 05:50:46.8:   Script started successfully
2019-04-08 05:50:56.8: Sequencer is now active
2019-04-08 05:51:12.4: Sequencer is no longer active
2019-04-08 05:51:14.0: Observatory startup succeeded
2019-04-08 05:51:14.2: ++ Auto Focus ++
2019-04-08 05:51:14.2: Doing initial autofocus.
2019-04-08 05:51:14.2: Start special ACP AutoFocus script for scheduler
2019-04-08 05:51:14.2: ACP.RunScript(AutoFocusScheduler, ):
2019-04-08 05:51:14.2:   Script StartupObs is loaded, and
2019-04-08 05:51:14.2:   the console is idle.
2019-04-08 05:51:14.2:   In start loop, i = 0
2019-04-08 05:51:14.2:   Console is now idle
2019-04-08 05:51:14.2:   Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-04-08 05:51:14.2:   Script started successfully
2019-04-08 05:51:24.2: Sequencer is now active
2019-04-08 05:54:08.2: Sequencer is no longer active
2019-04-08 05:54:08.2: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-04-08 05:54:08.2: SelectRunning: No running plans
2019-04-08 05:54:08.3: Obs Jup in Plan Jup was vetoed by Horizon constraint.
2019-04-08 05:54:08.3:     Horizon estimates ready after 08-Apr-2019 09:10:40 UTC
2019-04-08 05:54:08.3: EPH (Jupiter): type=Major Planet time=05:54:08 ra=17.5717485 dec=-22.670750
2019-04-08 05:54:08.3: Obs Jupiter in Plan Jupiter was vetoed by Horizon constraint.
2019-04-08 05:54:08.3:     Horizon estimates ready after 08-Apr-2019 09:10:43 UTC
2019-04-08 05:54:08.3: Obs piOri in Plan piOri was vetoed by Horizon constraint.
2019-04-08 05:54:08.3:     Horizon estimates ready after 08-Apr-2019 18:28:02 UTC
2019-04-08 05:54:08.3: Obs upsOri in Plan upsOri was vetoed by Horizon constraint.
2019-04-08 05:54:08.3:     Horizon estimates ready after 08-Apr-2019 19:55:03 UTC
2019-04-08 05:54:08.3: Efficiency: Only one pending plan WD1145+017, it is the "best"
2019-04-08 05:54:08.3: Dispatcher cycle time: 0.2 sec.
2019-04-08 05:54:08.3: Acquire data for Observation WD1145+017...
2019-04-08 05:54:08.3:   (belongs to Project ASTR402_Spring2019, Plan WD1145+017)
2019-04-08 05:54:08.3: Doing 60 sets
2019-04-08 05:54:10.2: ++ Auto Focus ++
2019-04-08 05:54:10.3: Doing periodic autofocus.
2019-04-08 05:54:10.3: Start special ACP AutoFocus script for scheduler
2019-04-08 05:54:10.3: ACP.RunScript(AutoFocusScheduler, ):
2019-04-08 05:54:10.3:   Script AutoFocusScheduler is loaded, and
2019-04-08 05:54:10.3:   the console is idle.
2019-04-08 05:54:10.3:   In start loop, i = 0
2019-04-08 05:54:10.3:   Console is now idle
2019-04-08 05:54:10.3:   Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-04-08 05:54:10.3:   Script started successfully
2019-04-08 05:54:20.3: Sequencer is now active
2019-04-08 05:56:14.0: Sequencer is no longer active
2019-04-08 05:56:14.0: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-04-08 05:56:14.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 05:56:14.0: ACP.RunScript(AcquireScheduler, ):
2019-04-08 05:56:14.0:   Script AutoFocusScheduler is loaded, and
2019-04-08 05:56:14.0:   the console is idle.
2019-04-08 05:56:14.0:   In start loop, i = 0
2019-04-08 05:56:14.0:   Console is now idle
2019-04-08 05:56:14.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 05:56:14.3:   Script started successfully
2019-04-08 05:56:24.3: Sequencer is now active
2019-04-08 06:03:32.9: Sequencer is no longer active
2019-04-08 06:03:32.9: Post-job status check done (stat=Completed)
2019-04-08 06:03:32.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:03:32.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:03:32.9:   Script AcquireScheduler is loaded, and
2019-04-08 06:03:32.9:   the console is idle.
2019-04-08 06:03:32.9:   In start loop, i = 0
2019-04-08 06:03:32.9:   Console is now idle
2019-04-08 06:03:33.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:03:33.2:   Script started successfully
2019-04-08 06:03:43.2: Sequencer is now active
2019-04-08 06:09:35.9: Sequencer is no longer active
2019-04-08 06:09:35.9: Post-job status check done (stat=Completed)
2019-04-08 06:09:35.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:09:35.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:09:35.9:   Script AcquireScheduler is loaded, and
2019-04-08 06:09:35.9:   the console is idle.
2019-04-08 06:09:35.9:   In start loop, i = 0
2019-04-08 06:09:35.9:   Console is now idle
2019-04-08 06:09:36.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:09:36.2:   Script started successfully
2019-04-08 06:09:46.2: Sequencer is now active
2019-04-08 06:15:42.6: Sequencer is no longer active
2019-04-08 06:15:42.6: Post-job status check done (stat=Completed)
2019-04-08 06:15:42.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:15:42.6: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:15:42.6:   Script AcquireScheduler is loaded, and
2019-04-08 06:15:42.6:   the console is idle.
2019-04-08 06:15:42.6:   In start loop, i = 0
2019-04-08 06:15:42.6:   Console is now idle
2019-04-08 06:15:43.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:15:43.0:   Script started successfully
2019-04-08 06:15:53.0: Sequencer is now active
2019-04-08 06:21:43.8: Sequencer is no longer active
2019-04-08 06:21:43.8: Post-job status check done (stat=Completed)
2019-04-08 06:21:43.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:21:43.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:21:43.8:   Script AcquireScheduler is loaded, and
2019-04-08 06:21:43.8:   the console is idle.
2019-04-08 06:21:43.8:   In start loop, i = 0
2019-04-08 06:21:43.8:   Console is now idle
2019-04-08 06:21:44.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:21:44.2:   Script started successfully
2019-04-08 06:21:54.2: Sequencer is now active
2019-04-08 06:27:42.4: Sequencer is no longer active
2019-04-08 06:27:42.4: Post-job status check done (stat=Completed)
2019-04-08 06:27:42.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:27:42.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:27:42.4:   Script AcquireScheduler is loaded, and
2019-04-08 06:27:42.4:   the console is idle.
2019-04-08 06:27:42.4:   In start loop, i = 0
2019-04-08 06:27:42.4:   Console is now idle
2019-04-08 06:27:42.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:27:42.8:   Script started successfully
2019-04-08 06:27:52.8: Sequencer is now active
2019-04-08 06:33:42.7: Sequencer is no longer active
2019-04-08 06:33:42.7: Post-job status check done (stat=Completed)
2019-04-08 06:33:42.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:33:42.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:33:42.8:   Script AcquireScheduler is loaded, and
2019-04-08 06:33:42.8:   the console is idle.
2019-04-08 06:33:42.8:   In start loop, i = 0
2019-04-08 06:33:42.8:   Console is now idle
2019-04-08 06:33:43.1:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:33:43.1:   Script started successfully
2019-04-08 06:33:53.1: Sequencer is now active
2019-04-08 06:39:43.5: Sequencer is no longer active
2019-04-08 06:39:43.5: Post-job status check done (stat=Completed)
2019-04-08 06:39:43.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:39:43.6: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:39:43.6:   Script AcquireScheduler is loaded, and
2019-04-08 06:39:43.6:   the console is idle.
2019-04-08 06:39:43.6:   In start loop, i = 0
2019-04-08 06:39:43.6:   Console is now idle
2019-04-08 06:39:43.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:39:43.9:   Script started successfully
2019-04-08 06:39:53.9: Sequencer is now active
2019-04-08 06:45:51.5: Sequencer is no longer active
2019-04-08 06:45:51.5: Post-job status check done (stat=Completed)
2019-04-08 06:45:51.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:45:51.5: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:45:51.5:   Script AcquireScheduler is loaded, and
2019-04-08 06:45:51.5:   the console is idle.
2019-04-08 06:45:51.5:   In start loop, i = 0
2019-04-08 06:45:51.5:   Console is now idle
2019-04-08 06:45:51.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:45:51.9:   Script started successfully
2019-04-08 06:46:01.9: Sequencer is now active
2019-04-08 06:51:49.8: Sequencer is no longer active
2019-04-08 06:51:49.8: Post-job status check done (stat=Completed)
2019-04-08 06:51:49.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:51:49.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:51:49.8:   Script AcquireScheduler is loaded, and
2019-04-08 06:51:49.8:   the console is idle.
2019-04-08 06:51:49.8:   In start loop, i = 0
2019-04-08 06:51:49.8:   Console is now idle
2019-04-08 06:51:50.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:51:50.2:   Script started successfully
2019-04-08 06:52:00.2: Sequencer is now active
2019-04-08 06:57:47.6: Sequencer is no longer active
2019-04-08 06:57:47.6: Post-job status check done (stat=Completed)
2019-04-08 06:57:47.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 06:57:47.6: ACP.RunScript(AcquireScheduler, ):
2019-04-08 06:57:47.6:   Script AcquireScheduler is loaded, and
2019-04-08 06:57:47.6:   the console is idle.
2019-04-08 06:57:47.6:   In start loop, i = 0
2019-04-08 06:57:47.6:   Console is now idle
2019-04-08 06:57:48.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 06:57:48.0:   Script started successfully
2019-04-08 06:57:58.0: Sequencer is now active
2019-04-08 07:03:46.3: Sequencer is no longer active
2019-04-08 07:03:46.3: Post-job status check done (stat=Completed)
2019-04-08 07:03:46.3: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:03:46.3: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:03:46.3:   Script AcquireScheduler is loaded, and
2019-04-08 07:03:46.3:   the console is idle.
2019-04-08 07:03:46.3:   In start loop, i = 0
2019-04-08 07:03:46.3:   Console is now idle
2019-04-08 07:03:46.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:03:46.7:   Script started successfully
2019-04-08 07:03:56.7: Sequencer is now active
2019-04-08 07:09:47.0: Sequencer is no longer active
2019-04-08 07:09:47.0: Post-job status check done (stat=Completed)
2019-04-08 07:09:47.1: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:09:47.1: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:09:47.1:   Script AcquireScheduler is loaded, and
2019-04-08 07:09:47.1:   the console is idle.
2019-04-08 07:09:47.1:   In start loop, i = 0
2019-04-08 07:09:47.1:   Console is now idle
2019-04-08 07:09:47.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:09:47.4:   Script started successfully
2019-04-08 07:09:57.4: Sequencer is now active
2019-04-08 07:15:56.8: Sequencer is no longer active
2019-04-08 07:15:56.8: Post-job status check done (stat=Completed)
2019-04-08 07:15:56.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:15:56.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:15:56.8:   Script AcquireScheduler is loaded, and
2019-04-08 07:15:56.8:   the console is idle.
2019-04-08 07:15:56.8:   In start loop, i = 0
2019-04-08 07:15:56.8:   Console is now idle
2019-04-08 07:15:57.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:15:57.2:   Script started successfully
2019-04-08 07:16:07.2: Sequencer is now active
2019-04-08 07:21:56.8: Sequencer is no longer active
2019-04-08 07:21:56.8: Post-job status check done (stat=Completed)
2019-04-08 07:21:56.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:21:56.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:21:56.8:   Script AcquireScheduler is loaded, and
2019-04-08 07:21:56.8:   the console is idle.
2019-04-08 07:21:56.8:   In start loop, i = 0
2019-04-08 07:21:56.8:   Console is now idle
2019-04-08 07:21:57.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:21:57.2:   Script started successfully
2019-04-08 07:22:07.2: Sequencer is now active
2019-04-08 07:27:53.4: Sequencer is no longer active
2019-04-08 07:27:53.4: Post-job status check done (stat=Completed)
2019-04-08 07:27:53.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:27:53.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:27:53.4:   Script AcquireScheduler is loaded, and
2019-04-08 07:27:53.4:   the console is idle.
2019-04-08 07:27:53.4:   In start loop, i = 0
2019-04-08 07:27:53.4:   Console is now idle
2019-04-08 07:27:53.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:27:53.7:   Script started successfully
2019-04-08 07:28:03.7: Sequencer is now active
2019-04-08 07:33:56.4: Sequencer is no longer active
2019-04-08 07:33:56.4: Post-job status check done (stat=Completed)
2019-04-08 07:33:56.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:33:56.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:33:56.4:   Script AcquireScheduler is loaded, and
2019-04-08 07:33:56.4:   the console is idle.
2019-04-08 07:33:56.4:   In start loop, i = 0
2019-04-08 07:33:56.4:   Console is now idle
2019-04-08 07:33:56.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:33:56.7:   Script started successfully
2019-04-08 07:34:06.7: Sequencer is now active
2019-04-08 07:39:58.9: Sequencer is no longer active
2019-04-08 07:39:58.9: Post-job status check done (stat=Completed)
2019-04-08 07:39:58.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:39:58.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:39:58.9:   Script AcquireScheduler is loaded, and
2019-04-08 07:39:58.9:   the console is idle.
2019-04-08 07:39:58.9:   In start loop, i = 0
2019-04-08 07:39:58.9:   Console is now idle
2019-04-08 07:39:59.3:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:39:59.3:   Script started successfully
2019-04-08 07:40:09.3: Sequencer is now active
2019-04-08 07:46:19.5: Sequencer is no longer active
2019-04-08 07:46:19.5: Post-job status check done (stat=Completed)
2019-04-08 07:46:19.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:46:19.5: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:46:19.5:   Script AcquireScheduler is loaded, and
2019-04-08 07:46:19.5:   the console is idle.
2019-04-08 07:46:19.5:   In start loop, i = 0
2019-04-08 07:46:19.6:   Console is now idle
2019-04-08 07:46:19.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:46:19.9:   Script started successfully
2019-04-08 07:46:29.9: Sequencer is now active
2019-04-08 07:52:26.6: Sequencer is no longer active
2019-04-08 07:52:26.6: Post-job status check done (stat=Completed)
2019-04-08 07:52:26.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:52:26.6: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:52:26.6:   Script AcquireScheduler is loaded, and
2019-04-08 07:52:26.6:   the console is idle.
2019-04-08 07:52:26.6:   In start loop, i = 0
2019-04-08 07:52:26.6:   Console is now idle
2019-04-08 07:52:26.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:52:26.9:   Script started successfully
2019-04-08 07:52:36.9: Sequencer is now active
2019-04-08 07:58:28.7: Sequencer is no longer active
2019-04-08 07:58:28.7: Post-job status check done (stat=Completed)
2019-04-08 07:58:28.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 07:58:28.7: ACP.RunScript(AcquireScheduler, ):
2019-04-08 07:58:28.7:   Script AcquireScheduler is loaded, and
2019-04-08 07:58:28.7:   the console is idle.
2019-04-08 07:58:28.7:   In start loop, i = 0
2019-04-08 07:58:28.7:   Console is now idle
2019-04-08 07:58:29.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 07:58:29.0:   Script started successfully
2019-04-08 07:58:39.0: Sequencer is now active
2019-04-08 08:04:28.0: Sequencer is no longer active
2019-04-08 08:04:28.0: Post-job status check done (stat=Completed)
2019-04-08 08:04:28.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:04:28.0: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:04:28.0:   Script AcquireScheduler is loaded, and
2019-04-08 08:04:28.0:   the console is idle.
2019-04-08 08:04:28.1:   In start loop, i = 0
2019-04-08 08:04:28.1:   Console is now idle
2019-04-08 08:04:28.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:04:28.4:   Script started successfully
2019-04-08 08:04:38.4: Sequencer is now active
2019-04-08 08:10:27.8: Sequencer is no longer active
2019-04-08 08:10:27.8: Post-job status check done (stat=Completed)
2019-04-08 08:10:27.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:10:27.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:10:27.9:   Script AcquireScheduler is loaded, and
2019-04-08 08:10:27.9:   the console is idle.
2019-04-08 08:10:27.9:   In start loop, i = 0
2019-04-08 08:10:27.9:   Console is now idle
2019-04-08 08:10:28.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:10:28.2:   Script started successfully
2019-04-08 08:10:38.2: Sequencer is now active
2019-04-08 08:16:31.2: Sequencer is no longer active
2019-04-08 08:16:31.2: Post-job status check done (stat=Completed)
2019-04-08 08:16:31.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:16:31.2: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:16:31.2:   Script AcquireScheduler is loaded, and
2019-04-08 08:16:31.2:   the console is idle.
2019-04-08 08:16:31.2:   In start loop, i = 0
2019-04-08 08:16:31.2:   Console is now idle
2019-04-08 08:16:31.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:16:31.6:   Script started successfully
2019-04-08 08:16:41.6: Sequencer is now active
2019-04-08 08:22:30.4: Sequencer is no longer active
2019-04-08 08:22:30.4: Post-job status check done (stat=Completed)
2019-04-08 08:22:30.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:22:30.5: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:22:30.5:   Script AcquireScheduler is loaded, and
2019-04-08 08:22:30.5:   the console is idle.
2019-04-08 08:22:30.5:   In start loop, i = 0
2019-04-08 08:22:30.5:   Console is now idle
2019-04-08 08:22:30.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:22:30.8:   Script started successfully
2019-04-08 08:22:40.8: Sequencer is now active
2019-04-08 08:28:31.8: Sequencer is no longer active
2019-04-08 08:28:31.8: Post-job status check done (stat=Completed)
2019-04-08 08:28:31.8: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:28:31.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:28:31.8:   Script AcquireScheduler is loaded, and
2019-04-08 08:28:31.8:   the console is idle.
2019-04-08 08:28:31.8:   In start loop, i = 0
2019-04-08 08:28:31.8:   Console is now idle
2019-04-08 08:28:32.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:28:32.2:   Script started successfully
2019-04-08 08:28:42.2: Sequencer is now active
2019-04-08 08:34:35.9: Sequencer is no longer active
2019-04-08 08:34:35.9: Post-job status check done (stat=Completed)
2019-04-08 08:34:35.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:34:35.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:34:35.9:   Script AcquireScheduler is loaded, and
2019-04-08 08:34:35.9:   the console is idle.
2019-04-08 08:34:35.9:   In start loop, i = 0
2019-04-08 08:34:35.9:   Console is now idle
2019-04-08 08:34:36.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:34:36.2:   Script started successfully
2019-04-08 08:34:46.2: Sequencer is now active
2019-04-08 08:40:39.5: Sequencer is no longer active
2019-04-08 08:40:39.5: Post-job status check done (stat=Completed)
2019-04-08 08:40:39.6: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:40:39.6: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:40:39.6:   Script AcquireScheduler is loaded, and
2019-04-08 08:40:39.6:   the console is idle.
2019-04-08 08:40:39.6:   In start loop, i = 0
2019-04-08 08:40:39.6:   Console is now idle
2019-04-08 08:40:39.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:40:39.9:   Script started successfully
2019-04-08 08:40:49.9: Sequencer is now active
2019-04-08 08:46:43.4: Sequencer is no longer active
2019-04-08 08:46:43.4: Post-job status check done (stat=Completed)
2019-04-08 08:46:43.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:46:43.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:46:43.4:   Script AcquireScheduler is loaded, and
2019-04-08 08:46:43.4:   the console is idle.
2019-04-08 08:46:43.4:   In start loop, i = 0
2019-04-08 08:46:43.4:   Console is now idle
2019-04-08 08:46:43.9:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:46:43.9:   Script started successfully
2019-04-08 08:46:53.9: Sequencer is now active
2019-04-08 08:52:56.5: Sequencer is no longer active
2019-04-08 08:52:56.5: Post-job status check done (stat=Completed)
2019-04-08 08:52:56.5: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:52:56.5: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:52:56.5:   Script AcquireScheduler is loaded, and
2019-04-08 08:52:56.5:   the console is idle.
2019-04-08 08:52:56.5:   In start loop, i = 0
2019-04-08 08:52:56.5:   Console is now idle
2019-04-08 08:52:56.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:52:56.8:   Script started successfully
2019-04-08 08:53:06.8: Sequencer is now active
2019-04-08 08:58:58.7: Sequencer is no longer active
2019-04-08 08:58:58.7: Post-job status check done (stat=Completed)
2019-04-08 08:58:58.7: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 08:58:58.7: ACP.RunScript(AcquireScheduler, ):
2019-04-08 08:58:58.7:   Script AcquireScheduler is loaded, and
2019-04-08 08:58:58.7:   the console is idle.
2019-04-08 08:58:58.7:   In start loop, i = 0
2019-04-08 08:58:58.7:   Console is now idle
2019-04-08 08:58:59.0:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 08:58:59.0:   Script started successfully
2019-04-08 08:59:09.0: Sequencer is now active
2019-04-08 09:05:06.2: Sequencer is no longer active
2019-04-08 09:05:06.2: Post-job status check done (stat=Completed)
2019-04-08 09:05:06.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:05:06.2: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:05:06.2:   Script AcquireScheduler is loaded, and
2019-04-08 09:05:06.2:   the console is idle.
2019-04-08 09:05:06.2:   In start loop, i = 0
2019-04-08 09:05:06.2:   Console is now idle
2019-04-08 09:05:06.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:05:06.6:   Script started successfully
2019-04-08 09:05:16.6: Sequencer is now active
2019-04-08 09:11:10.9: Sequencer is no longer active
2019-04-08 09:11:10.9: Post-job status check done (stat=Completed)
2019-04-08 09:11:10.9: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:11:10.9: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:11:10.9:   Script AcquireScheduler is loaded, and
2019-04-08 09:11:10.9:   the console is idle.
2019-04-08 09:11:10.9:   In start loop, i = 0
2019-04-08 09:11:10.9:   Console is now idle
2019-04-08 09:11:11.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:11:11.2:   Script started successfully
2019-04-08 09:11:21.2: Sequencer is now active
2019-04-08 09:17:17.4: Sequencer is no longer active
2019-04-08 09:17:17.4: Post-job status check done (stat=Completed)
2019-04-08 09:17:17.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:17:17.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:17:17.4:   Script AcquireScheduler is loaded, and
2019-04-08 09:17:17.4:   the console is idle.
2019-04-08 09:17:17.4:   In start loop, i = 0
2019-04-08 09:17:17.4:   Console is now idle
2019-04-08 09:17:17.8:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:17:17.8:   Script started successfully
2019-04-08 09:17:27.8: Sequencer is now active
2019-04-08 09:23:17.2: Sequencer is no longer active
2019-04-08 09:23:17.2: Post-job status check done (stat=Completed)
2019-04-08 09:23:17.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:23:17.2: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:23:17.2:   Script AcquireScheduler is loaded, and
2019-04-08 09:23:17.2:   the console is idle.
2019-04-08 09:23:17.2:   In start loop, i = 0
2019-04-08 09:23:17.2:   Console is now idle
2019-04-08 09:23:17.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:23:17.7:   Script started successfully
2019-04-08 09:23:27.7: Sequencer is now active
2019-04-08 09:29:17.0: Sequencer is no longer active
2019-04-08 09:29:17.0: Post-job status check done (stat=Completed)
2019-04-08 09:29:17.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:29:17.0: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:29:17.0:   Script AcquireScheduler is loaded, and
2019-04-08 09:29:17.0:   the console is idle.
2019-04-08 09:29:17.0:   In start loop, i = 0
2019-04-08 09:29:17.0:   Console is now idle
2019-04-08 09:29:17.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:29:17.4:   Script started successfully
2019-04-08 09:29:27.4: Sequencer is now active
2019-04-08 09:35:20.2: Sequencer is no longer active
2019-04-08 09:35:20.2: Post-job status check done (stat=Completed)
2019-04-08 09:35:20.2: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:35:20.2: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:35:20.2:   Script AcquireScheduler is loaded, and
2019-04-08 09:35:20.2:   the console is idle.
2019-04-08 09:35:20.2:   In start loop, i = 0
2019-04-08 09:35:20.2:   Console is now idle
2019-04-08 09:35:20.6:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:35:20.6:   Script started successfully
2019-04-08 09:35:30.6: Sequencer is now active
2019-04-08 09:41:18.4: Sequencer is no longer active
2019-04-08 09:41:18.4: Post-job status check done (stat=Completed)
2019-04-08 09:41:18.4: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:41:18.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:41:18.4:   Script AcquireScheduler is loaded, and
2019-04-08 09:41:18.4:   the console is idle.
2019-04-08 09:41:18.4:   In start loop, i = 0
2019-04-08 09:41:18.4:   Console is now idle
2019-04-08 09:41:18.7:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:41:18.7:   Script started successfully
2019-04-08 09:41:28.7: Sequencer is now active
2019-04-08 09:47:23.0: Sequencer is no longer active
2019-04-08 09:47:23.0: Post-job status check done (stat=Completed)
2019-04-08 09:47:23.0: Send Observation WD1145+017 to ACP Sequencer
2019-04-08 09:47:23.0: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:47:23.0:   Script AcquireScheduler is loaded, and
2019-04-08 09:47:23.0:   the console is idle.
2019-04-08 09:47:23.0:   In start loop, i = 0
2019-04-08 09:47:23.0:   Console is now idle
2019-04-08 09:47:23.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:47:23.4:   Script started successfully
2019-04-08 09:47:33.4: Sequencer is now active
2019-04-08 09:53:17.3: Sequencer is no longer active
2019-04-08 09:53:17.3: Post-job status check done (stat=Completed)
2019-04-08 09:53:17.3: TestConstraints: Horizon vetoed/failed for end time of Obs
2019-04-08 09:53:17.3: ***Running Plan WD1145+017 (best efforts) completed early:
2019-04-08 09:53:17.3:    Obs WD1145+017 would not meet strict constraints
2019-04-08 09:53:17.3: Acquisition time: 14222.9049125 sec.
2019-04-08 09:53:17.3: Data for Observation WD1145+017 of Plan WD1145+017 acquired successfully.
2019-04-08 09:53:17.4: Plan WD1145+017 completed successfully.
2019-04-08 09:53:17.4: Image Efficiency: 82.3%
2019-04-08 09:53:17.4: Cycle Efficiency: 100.0%
2019-04-08 09:53:20.9: ++ Auto Focus ++
2019-04-08 09:53:21.0: Doing periodic autofocus.
2019-04-08 09:53:21.0: Start special ACP AutoFocus script for scheduler
2019-04-08 09:53:21.0: ACP.RunScript(AutoFocusScheduler, ):
2019-04-08 09:53:21.0:   Script AcquireScheduler is loaded, and
2019-04-08 09:53:21.0:   the console is idle.
2019-04-08 09:53:21.0:   In start loop, i = 0
2019-04-08 09:53:21.0:   Console is now idle
2019-04-08 09:53:21.0:   Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-04-08 09:53:21.0:   Script started successfully
2019-04-08 09:53:31.0: Sequencer is now active
2019-04-08 09:57:31.5: Sequencer is no longer active
2019-04-08 09:57:31.5: Next periodic autofocus ASAP after 08-Apr-2019 12:57:12 UTC
2019-04-08 09:57:31.5: SelectRunning: No running plans
2019-04-08 09:57:31.6: EPH (Jupiter): type=Major Planet time=09:57:31 ra=17.5718380 dec=-22.670783
2019-04-08 09:57:31.6: Efficiency: Selecting from 2 pending plans:
2019-04-08 09:57:31.6:   Selected Plan Jupiter (TotEff=1.58)
2019-04-08 09:57:31.7: Dispatcher cycle time: 0.2 sec.
2019-04-08 09:57:31.7: Acquire data for Observation Jupiter...
2019-04-08 09:57:31.7:   (belongs to Project ASTR402_Spring2019, Plan Jupiter)
2019-04-08 09:57:31.7: Doing 5 sets
2019-04-08 09:57:34.4: Send Observation Jupiter to ACP Sequencer
2019-04-08 09:57:34.4: ACP.RunScript(AcquireScheduler, ):
2019-04-08 09:57:34.4:   Script AutoFocusScheduler is loaded, and
2019-04-08 09:57:34.4:   the console is idle.
2019-04-08 09:57:34.4:   In start loop, i = 0
2019-04-08 09:57:34.4:   Console is now idle
2019-04-08 09:57:34.4:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 09:57:34.4:   Script started successfully
2019-04-08 09:57:44.4: Sequencer is now active
2019-04-08 10:04:49.1: Sequencer is no longer active
2019-04-08 10:04:49.1: Post-job status check done (stat=Completed)
2019-04-08 10:04:49.2: Send Observation Jupiter to ACP Sequencer
2019-04-08 10:04:49.2: ACP.RunScript(AcquireScheduler, ):
2019-04-08 10:04:49.2:   Script AcquireScheduler is loaded, and
2019-04-08 10:04:49.2:   the console is idle.
2019-04-08 10:04:49.2:   In start loop, i = 0
2019-04-08 10:04:49.2:   Console is now idle
2019-04-08 10:04:49.5:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 10:04:49.5:   Script started successfully
2019-04-08 10:04:59.5: Sequencer is now active
2019-04-08 10:08:48.8: Sequencer is no longer active
2019-04-08 10:08:48.8: Post-job status check done (stat=Completed)
2019-04-08 10:08:48.8: Send Observation Jupiter to ACP Sequencer
2019-04-08 10:08:48.8: ACP.RunScript(AcquireScheduler, ):
2019-04-08 10:08:48.8:   Script AcquireScheduler is loaded, and
2019-04-08 10:08:48.8:   the console is idle.
2019-04-08 10:08:48.8:   In start loop, i = 0
2019-04-08 10:08:48.8:   Console is now idle
2019-04-08 10:08:49.2:   Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully
2019-04-08 10:08:49.2:   Script started successfully
2019-04-08 10:08:59.2: Sequencer is now active
2019-04-08 12:12:12.5: Sequencer is no longer active
2019-04-08 12:12:15.5: Post-job status check done (stat=Running)
2019-04-08 12:12:15.5: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-04-08 12:12:15.5: ACP data acquisition failed for Observation Jupiter.
2019-04-08 12:12:15.5:   (Observation Jupiter interrupted by acquisition process failure.)
2019-04-08 12:12:15.7: -- Weather Unsafe --
2019-04-08 12:12:16.7: Skipping dawn sky flats because the weather was unsafe at sky-flat time
2019-04-08 12:12:47.7: -- Observatory Shutdown --
2019-04-08 12:12:47.7: Start ACP Sequencer's ShutdownObs script
2019-04-08 12:12:47.7: ACP.RunScript(ShutdownObs, ):
2019-04-08 12:12:47.7:   Script ACP-Weather is loaded, and
2019-04-08 12:12:47.7:   the console is RUNNING.
2019-04-08 12:12:47.7:   In start loop, i = 0
2019-04-08 12:12:47.7:   Console is busy, and foundIdle is False
2019-04-08 12:12:47.7: WARNING: Request sent while Weather Safety Script is running
2019-04-08 12:13:47.7:   In start loop, i = 1
2019-04-08 12:13:47.7:   Console is now idle
2019-04-08 12:13:48.0:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\ShutdownObs.js successfully
2019-04-08 12:13:48.0:   Script started successfully
2019-04-08 12:13:58.0: Sequencer is now active
2019-04-08 12:16:06.9: Sequencer is no longer active
2019-04-08 16:08:47.4: Dispatcher stopped at 08-Apr-2019 16:08:47 UTC
2019-04-08 16:08:47.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-04-08 18:43:50.4: Weather successfully connected. Wait for valid data...
2019-04-09 02:01:48.8: ++ Weather Safe ++
2019-04-09 03:14:16.0: -- Weather Unsafe --
2019-04-09 03:44:53.1: ++ Weather Safe ++
2019-04-09 04:47:43.8: -- Weather Unsafe --
2019-04-09 05:37:07.2: ++ Weather Safe ++
2019-04-09 12:40:16.9: -- Weather Unsafe --
2019-04-10 06:03:52.0: ++ Weather Safe ++
2019-04-10 12:34:54.8: -- Weather Unsafe --
2019-04-11 11:47:47.9: ++ Weather Safe ++
2019-04-11 12:31:33.7: -- Weather Unsafe --
2019-04-12 06:31:33.2: ++ Weather Safe ++
2019-04-12 06:42:39.6: -- Weather Unsafe --
2019-04-12 07:13:33.2: ++ Weather Safe ++
2019-04-12 12:42:44.5: -- Weather Unsafe --
2019-04-13 02:07:51.4: ++ Weather Safe ++
2019-04-13 12:32:38.9: -- Weather Unsafe --
2019-04-14 02:12:44.3: ++ Weather Safe ++
2019-04-14 12:28:40.6: -- Weather Unsafe --
2019-04-15 02:05:54.5: ++ Weather Safe ++
2019-04-15 02:33:22.6: -- Weather Unsafe --
2019-04-15 05:21:54.0: ++ Weather Safe ++
2019-04-15 12:38:03.2: -- Weather Unsafe --
2019-04-16 02:04:14.2: ++ Weather Safe ++
2019-04-16 04:07:11.5: -- Weather Unsafe --
2019-04-16 06:34:24.0: ++ Weather Safe ++
2019-04-16 07:17:55.7: -- Weather Unsafe --
2019-04-16 09:46:06.9: ++ Weather Safe ++
2019-04-16 10:10:39.1: -- Weather Unsafe --
2019-04-16 11:10:04.1: ++ Weather Safe ++
2019-04-16 11:41:02.8: -- Weather Unsafe --
2019-04-16 12:34:50.9: ++ Weather Safe ++
2019-04-16 12:39:25.1: -- Weather Unsafe --
2019-04-17 06:45:46.8: ++ Weather Safe ++
2019-04-17 07:15:22.6: -- Weather Unsafe --
2019-04-18 03:31:38.4: ++ Weather Safe ++
2019-04-18 12:26:29.7: -- Weather Unsafe --
2019-04-19 02:07:46.7: ++ Weather Safe ++
2019-04-19 12:27:22.5: -- Weather Unsafe --