Log opened at Fri, Apr 05 2019 21:41:24 UTC (actual time) Current log level is Debug ACP Expert Scheduler version 8.1.1 Full license: Arne Henden 2019-04-05 21:41:24.7: Internal clock initialized at 05-Apr-2019 21:41:24 UTC 2019-04-05 21:41:24.7: Loaded Constraint plugin AirMass 2019-04-05 21:41:24.7: Loaded Constraint plugin AirmassRange 2019-04-05 21:41:24.7: Loaded Constraint plugin Horizon 2019-04-05 21:41:24.7: Loaded Constraint plugin HourAngle 2019-04-05 21:41:24.7: Loaded Constraint plugin MoonAvoid 2019-04-05 21:41:24.7: Loaded Constraint plugin MoonDown 2019-04-05 21:41:24.7: Loaded Constraint plugin SkyCondition 2019-04-05 21:41:24.7: Loaded Constraint plugin TimeRange 2019-04-05 21:41:25.9: 5 plans are now pending. 2019-04-05 21:41:25.9: Attach ACP sequencer 2019-04-05 21:41:33.6: Sequencer does sky flats 2019-04-06 02:07:34.2: Dispatcher started at 06-Apr-2019 02:07:34 UTC 2019-04-06 02:07:34.2: Priority: W[0]=1.00 2019-04-06 02:07:34.2: Transit Altitude: W[1]=0.70 2019-04-06 02:07:34.2: Highest Altitude: W[6]=0.00 2019-04-06 02:07:34.2: Lateness: W[5]=0.00 2019-04-06 02:07:34.2: Slew Distance: W[2]=0.30 2019-04-06 02:07:34.2: Retry Count: W[3]=0.00 2019-04-06 02:07:34.2: Meridian Crossing: W[4]=0.00 2019-04-06 02:07:34.2: Obs Conditions: W[7]=0.40 2019-04-06 02:07:34.2: Rising Plan Delay: disabled 2019-04-06 02:13:45.4: ++ Observatory Startup ++ 2019-04-06 02:13:45.4: Start ACP Sequencer's StartupObs script 2019-04-06 02:13:45.4: ACP.RunScript(StartupObs, ): 2019-04-06 02:13:45.4: Script ACP-Weather is loaded, and 2019-04-06 02:13:45.4: the console is idle. 2019-04-06 02:13:45.4: In start loop, i = 0 2019-04-06 02:13:45.4: Console is now idle 2019-04-06 02:13:45.4: Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully 2019-04-06 02:13:45.4: Script started successfully 2019-04-06 02:13:55.4: Sequencer is now active 2019-04-06 02:14:06.7: Sequencer is no longer active 2019-04-06 02:14:06.7: Observatory startup succeeded in unsafe weather. 2019-04-06 03:08:57.0: ++ Weather Safe ++ 2019-04-06 03:08:58.1: ++ Observatory Startup ++ 2019-04-06 03:08:58.1: Start ACP Sequencer's StartupObs script 2019-04-06 03:08:58.1: ACP.RunScript(StartupObs, ): 2019-04-06 03:08:58.1: Script StartupObs is loaded, and 2019-04-06 03:08:58.1: the console is idle. 2019-04-06 03:08:58.1: In start loop, i = 0 2019-04-06 03:08:58.1: Console is now idle 2019-04-06 03:08:58.1: Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully 2019-04-06 03:08:58.1: Script started successfully 2019-04-06 03:09:08.1: Sequencer is now active 2019-04-06 03:09:20.0: Sequencer is no longer active 2019-04-06 03:09:20.4: Open the observatory dome/roof 2019-04-06 03:10:44.6: Observatory startup succeeded, and dome opened successfully 2019-04-06 03:10:45.9: ++ Auto Focus ++ 2019-04-06 03:10:45.9: Doing initial autofocus. 2019-04-06 03:10:45.9: Start special ACP AutoFocus script for scheduler 2019-04-06 03:10:45.9: ACP.RunScript(AutoFocusScheduler, ): 2019-04-06 03:10:45.9: Script StartupObs is loaded, and 2019-04-06 03:10:45.9: the console is idle. 2019-04-06 03:10:45.9: In start loop, i = 0 2019-04-06 03:10:45.9: Console is now idle 2019-04-06 03:10:46.0: Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-04-06 03:10:46.0: Script started successfully 2019-04-06 03:10:56.0: Sequencer is now active 2019-04-06 03:15:32.6: Sequencer is no longer active 2019-04-06 03:15:32.6: ** Autofocus failed (n=1), will retry ASAP after 0 min. 2019-04-06 03:15:34.0: SelectRunning: No running plans 2019-04-06 03:15:34.0: EPH (136472Makemake): type=Minor Planet time=03:15:34 ra=13.0730680 dec=24.412026 2019-04-06 03:15:34.1: Obs Jup in Plan Jup was vetoed by Horizon constraint. 2019-04-06 03:15:34.1: Horizon estimates ready after 06-Apr-2019 09:18:20 UTC 2019-04-06 03:15:34.1: EPH (Jupiter): type=Major Planet time=03:15:34 ra=17.5700870 dec=-22.670035 2019-04-06 03:15:34.1: Obs Jupiter in Plan Jupiter was vetoed by Horizon constraint. 2019-04-06 03:15:34.1: Horizon estimates ready after 06-Apr-2019 09:18:29 UTC 2019-04-06 03:15:34.1: Efficiency: Selecting from 4 pending plans: 2019-04-06 03:15:34.2: Selected Plan WD1145+017 (TotEff=1.48) 2019-04-06 03:15:34.2: Dispatcher cycle time: 0.8 sec. 2019-04-06 03:15:34.2: Acquire data for Observation WD1145+017... 2019-04-06 03:15:34.2: (belongs to Project ASTR402_Spring2019, Plan WD1145+017) 2019-04-06 03:15:34.2: Doing 60 sets 2019-04-06 03:15:34.2: ++ Auto Focus ++ 2019-04-06 03:15:34.3: Doing periodic autofocus. 2019-04-06 03:15:34.3: Start special ACP AutoFocus script for scheduler 2019-04-06 03:15:34.3: ACP.RunScript(AutoFocusScheduler, ): 2019-04-06 03:15:34.3: Script AutoFocusScheduler is loaded, and 2019-04-06 03:15:34.3: the console is idle. 2019-04-06 03:15:34.3: In start loop, i = 0 2019-04-06 03:15:34.3: Console is now idle 2019-04-06 03:15:34.3: Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-04-06 03:15:34.3: Script started successfully 2019-04-06 03:15:44.3: Sequencer is now active 2019-04-06 03:18:26.0: Sequencer is no longer active 2019-04-06 03:18:26.0: Next periodic autofocus ASAP after 06-Apr-2019 06:18:06 UTC 2019-04-06 03:18:26.1: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:18:26.1: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:18:26.1: Script AutoFocusScheduler is loaded, and 2019-04-06 03:18:26.1: the console is idle. 2019-04-06 03:18:26.1: In start loop, i = 0 2019-04-06 03:18:26.1: Console is now idle 2019-04-06 03:18:26.4: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:18:26.4: Script started successfully 2019-04-06 03:18:36.4: Sequencer is now active 2019-04-06 03:27:14.6: Sequencer is no longer active 2019-04-06 03:27:14.6: Post-job status check done (stat=Completed) 2019-04-06 03:27:14.6: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:27:14.6: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:27:14.6: Script AcquireScheduler is loaded, and 2019-04-06 03:27:14.6: the console is idle. 2019-04-06 03:27:14.6: In start loop, i = 0 2019-04-06 03:27:14.6: Console is now idle 2019-04-06 03:27:14.9: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:27:14.9: Script started successfully 2019-04-06 03:27:24.9: Sequencer is now active 2019-04-06 03:33:18.0: Sequencer is no longer active 2019-04-06 03:33:18.0: Post-job status check done (stat=Completed) 2019-04-06 03:33:18.0: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:33:18.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:33:18.0: Script AcquireScheduler is loaded, and 2019-04-06 03:33:18.0: the console is idle. 2019-04-06 03:33:18.0: In start loop, i = 0 2019-04-06 03:33:18.0: Console is now idle 2019-04-06 03:33:18.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:33:18.3: Script started successfully 2019-04-06 03:33:28.3: Sequencer is now active 2019-04-06 03:39:13.7: Sequencer is no longer active 2019-04-06 03:39:13.7: Post-job status check done (stat=Completed) 2019-04-06 03:39:13.7: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:39:13.7: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:39:13.7: Script AcquireScheduler is loaded, and 2019-04-06 03:39:13.7: the console is idle. 2019-04-06 03:39:13.7: In start loop, i = 0 2019-04-06 03:39:13.7: Console is now idle 2019-04-06 03:39:14.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:39:14.0: Script started successfully 2019-04-06 03:39:24.0: Sequencer is now active 2019-04-06 03:45:12.4: Sequencer is no longer active 2019-04-06 03:45:12.4: Post-job status check done (stat=Completed) 2019-04-06 03:45:12.4: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:45:12.4: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:45:12.4: Script AcquireScheduler is loaded, and 2019-04-06 03:45:12.4: the console is idle. 2019-04-06 03:45:12.4: In start loop, i = 0 2019-04-06 03:45:12.4: Console is now idle 2019-04-06 03:45:12.7: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:45:12.7: Script started successfully 2019-04-06 03:45:22.7: Sequencer is now active 2019-04-06 03:51:11.2: Sequencer is no longer active 2019-04-06 03:51:11.2: Post-job status check done (stat=Completed) 2019-04-06 03:51:11.2: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:51:11.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:51:11.2: Script AcquireScheduler is loaded, and 2019-04-06 03:51:11.2: the console is idle. 2019-04-06 03:51:11.2: In start loop, i = 0 2019-04-06 03:51:11.2: Console is now idle 2019-04-06 03:51:11.6: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:51:11.6: Script started successfully 2019-04-06 03:51:21.6: Sequencer is now active 2019-04-06 03:57:07.5: Sequencer is no longer active 2019-04-06 03:57:07.5: Post-job status check done (stat=Completed) 2019-04-06 03:57:07.6: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 03:57:07.6: ACP.RunScript(AcquireScheduler, ): 2019-04-06 03:57:07.6: Script AcquireScheduler is loaded, and 2019-04-06 03:57:07.6: the console is idle. 2019-04-06 03:57:07.6: In start loop, i = 0 2019-04-06 03:57:07.6: Console is now idle 2019-04-06 03:57:07.9: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 03:57:07.9: Script started successfully 2019-04-06 03:57:17.9: Sequencer is now active 2019-04-06 04:03:04.4: Sequencer is no longer active 2019-04-06 04:03:04.4: Post-job status check done (stat=Completed) 2019-04-06 04:03:04.5: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:03:04.5: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:03:04.5: Script AcquireScheduler is loaded, and 2019-04-06 04:03:04.5: the console is idle. 2019-04-06 04:03:04.5: In start loop, i = 0 2019-04-06 04:03:04.5: Console is now idle 2019-04-06 04:03:04.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:03:04.8: Script started successfully 2019-04-06 04:03:14.8: Sequencer is now active 2019-04-06 04:09:03.2: Sequencer is no longer active 2019-04-06 04:09:03.2: Post-job status check done (stat=Completed) 2019-04-06 04:09:03.2: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:09:03.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:09:03.2: Script AcquireScheduler is loaded, and 2019-04-06 04:09:03.2: the console is idle. 2019-04-06 04:09:03.2: In start loop, i = 0 2019-04-06 04:09:03.2: Console is now idle 2019-04-06 04:09:03.6: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:09:03.6: Script started successfully 2019-04-06 04:09:13.6: Sequencer is now active 2019-04-06 04:15:04.0: Sequencer is no longer active 2019-04-06 04:15:04.0: Post-job status check done (stat=Completed) 2019-04-06 04:15:04.0: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:15:04.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:15:04.0: Script AcquireScheduler is loaded, and 2019-04-06 04:15:04.0: the console is idle. 2019-04-06 04:15:04.0: In start loop, i = 0 2019-04-06 04:15:04.0: Console is now idle 2019-04-06 04:15:04.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:15:04.3: Script started successfully 2019-04-06 04:15:14.3: Sequencer is now active 2019-04-06 04:21:13.0: Sequencer is no longer active 2019-04-06 04:21:13.0: Post-job status check done (stat=Completed) 2019-04-06 04:21:13.1: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:21:13.1: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:21:13.1: Script AcquireScheduler is loaded, and 2019-04-06 04:21:13.1: the console is idle. 2019-04-06 04:21:13.1: In start loop, i = 0 2019-04-06 04:21:13.1: Console is now idle 2019-04-06 04:21:13.4: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:21:13.4: Script started successfully 2019-04-06 04:21:23.4: Sequencer is now active 2019-04-06 04:27:09.1: Sequencer is no longer active 2019-04-06 04:27:09.1: Post-job status check done (stat=Completed) 2019-04-06 04:27:09.1: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:27:09.1: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:27:09.1: Script AcquireScheduler is loaded, and 2019-04-06 04:27:09.1: the console is idle. 2019-04-06 04:27:09.1: In start loop, i = 0 2019-04-06 04:27:09.1: Console is now idle 2019-04-06 04:27:09.4: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:27:09.4: Script started successfully 2019-04-06 04:27:19.4: Sequencer is now active 2019-04-06 04:33:07.7: Sequencer is no longer active 2019-04-06 04:33:07.7: Post-job status check done (stat=Completed) 2019-04-06 04:33:07.7: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:33:07.7: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:33:07.7: Script AcquireScheduler is loaded, and 2019-04-06 04:33:07.7: the console is idle. 2019-04-06 04:33:07.7: In start loop, i = 0 2019-04-06 04:33:07.7: Console is now idle 2019-04-06 04:33:08.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:33:08.0: Script started successfully 2019-04-06 04:33:18.0: Sequencer is now active 2019-04-06 04:39:06.2: Sequencer is no longer active 2019-04-06 04:39:06.2: Post-job status check done (stat=Completed) 2019-04-06 04:39:06.2: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:39:06.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:39:06.2: Script AcquireScheduler is loaded, and 2019-04-06 04:39:06.2: the console is idle. 2019-04-06 04:39:06.2: In start loop, i = 0 2019-04-06 04:39:06.2: Console is now idle 2019-04-06 04:39:06.5: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:39:06.5: Script started successfully 2019-04-06 04:39:16.5: Sequencer is now active 2019-04-06 04:45:04.8: Sequencer is no longer active 2019-04-06 04:45:04.8: Post-job status check done (stat=Completed) 2019-04-06 04:45:04.9: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:45:04.9: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:45:04.9: Script AcquireScheduler is loaded, and 2019-04-06 04:45:04.9: the console is idle. 2019-04-06 04:45:04.9: In start loop, i = 0 2019-04-06 04:45:04.9: Console is now idle 2019-04-06 04:45:05.2: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:45:05.2: Script started successfully 2019-04-06 04:45:15.2: Sequencer is now active 2019-04-06 04:51:01.4: Sequencer is no longer active 2019-04-06 04:51:01.4: Post-job status check done (stat=Completed) 2019-04-06 04:51:01.4: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:51:01.4: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:51:01.4: Script AcquireScheduler is loaded, and 2019-04-06 04:51:01.4: the console is idle. 2019-04-06 04:51:01.4: In start loop, i = 0 2019-04-06 04:51:01.4: Console is now idle 2019-04-06 04:51:01.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:51:01.8: Script started successfully 2019-04-06 04:51:11.8: Sequencer is now active 2019-04-06 04:57:00.1: Sequencer is no longer active 2019-04-06 04:57:00.1: Post-job status check done (stat=Completed) 2019-04-06 04:57:00.1: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 04:57:00.1: ACP.RunScript(AcquireScheduler, ): 2019-04-06 04:57:00.1: Script AcquireScheduler is loaded, and 2019-04-06 04:57:00.1: the console is idle. 2019-04-06 04:57:00.1: In start loop, i = 0 2019-04-06 04:57:00.1: Console is now idle 2019-04-06 04:57:00.4: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 04:57:00.4: Script started successfully 2019-04-06 04:57:10.4: Sequencer is now active 2019-04-06 05:02:56.4: Sequencer is no longer active 2019-04-06 05:02:56.4: Post-job status check done (stat=Completed) 2019-04-06 05:02:56.5: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:02:56.5: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:02:56.5: Script AcquireScheduler is loaded, and 2019-04-06 05:02:56.5: the console is idle. 2019-04-06 05:02:56.5: In start loop, i = 0 2019-04-06 05:02:56.5: Console is now idle 2019-04-06 05:02:56.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:02:56.8: Script started successfully 2019-04-06 05:03:06.8: Sequencer is now active 2019-04-06 05:08:56.4: Sequencer is no longer active 2019-04-06 05:08:56.4: Post-job status check done (stat=Completed) 2019-04-06 05:08:56.4: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:08:56.4: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:08:56.4: Script AcquireScheduler is loaded, and 2019-04-06 05:08:56.4: the console is idle. 2019-04-06 05:08:56.4: In start loop, i = 0 2019-04-06 05:08:56.4: Console is now idle 2019-04-06 05:08:56.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:08:56.8: Script started successfully 2019-04-06 05:09:06.8: Sequencer is now active 2019-04-06 05:15:01.0: Sequencer is no longer active 2019-04-06 05:15:01.0: Post-job status check done (stat=Completed) 2019-04-06 05:15:01.0: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:15:01.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:15:01.0: Script AcquireScheduler is loaded, and 2019-04-06 05:15:01.0: the console is idle. 2019-04-06 05:15:01.0: In start loop, i = 0 2019-04-06 05:15:01.0: Console is now idle 2019-04-06 05:15:01.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:15:01.3: Script started successfully 2019-04-06 05:15:11.3: Sequencer is now active 2019-04-06 05:20:59.7: Sequencer is no longer active 2019-04-06 05:20:59.7: Post-job status check done (stat=Completed) 2019-04-06 05:20:59.7: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:20:59.7: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:20:59.7: Script AcquireScheduler is loaded, and 2019-04-06 05:20:59.7: the console is idle. 2019-04-06 05:20:59.7: In start loop, i = 0 2019-04-06 05:20:59.7: Console is now idle 2019-04-06 05:21:00.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:21:00.0: Script started successfully 2019-04-06 05:21:10.0: Sequencer is now active 2019-04-06 05:26:57.2: Sequencer is no longer active 2019-04-06 05:26:57.2: Post-job status check done (stat=Completed) 2019-04-06 05:26:57.2: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:26:57.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:26:57.2: Script AcquireScheduler is loaded, and 2019-04-06 05:26:57.2: the console is idle. 2019-04-06 05:26:57.2: In start loop, i = 0 2019-04-06 05:26:57.2: Console is now idle 2019-04-06 05:26:57.5: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:26:57.5: Script started successfully 2019-04-06 05:27:07.5: Sequencer is now active 2019-04-06 05:32:54.8: Sequencer is no longer active 2019-04-06 05:32:54.8: Post-job status check done (stat=Completed) 2019-04-06 05:32:54.9: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:32:54.9: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:32:54.9: Script AcquireScheduler is loaded, and 2019-04-06 05:32:54.9: the console is idle. 2019-04-06 05:32:54.9: In start loop, i = 0 2019-04-06 05:32:54.9: Console is now idle 2019-04-06 05:32:55.2: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:32:55.2: Script started successfully 2019-04-06 05:33:05.2: Sequencer is now active 2019-04-06 05:38:52.4: Sequencer is no longer active 2019-04-06 05:38:52.4: Post-job status check done (stat=Completed) 2019-04-06 05:38:52.5: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:38:52.5: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:38:52.5: Script AcquireScheduler is loaded, and 2019-04-06 05:38:52.5: the console is idle. 2019-04-06 05:38:52.5: In start loop, i = 0 2019-04-06 05:38:52.5: Console is now idle 2019-04-06 05:38:52.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:38:52.8: Script started successfully 2019-04-06 05:39:02.8: Sequencer is now active 2019-04-06 05:44:51.0: Sequencer is no longer active 2019-04-06 05:44:51.0: Post-job status check done (stat=Completed) 2019-04-06 05:44:51.0: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:44:51.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:44:51.0: Script AcquireScheduler is loaded, and 2019-04-06 05:44:51.0: the console is idle. 2019-04-06 05:44:51.0: In start loop, i = 0 2019-04-06 05:44:51.0: Console is now idle 2019-04-06 05:44:51.4: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:44:51.4: Script started successfully 2019-04-06 05:45:01.4: Sequencer is now active 2019-04-06 05:50:51.6: Sequencer is no longer active 2019-04-06 05:50:51.6: Post-job status check done (stat=Completed) 2019-04-06 05:50:51.6: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:50:51.6: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:50:51.6: Script AcquireScheduler is loaded, and 2019-04-06 05:50:51.6: the console is idle. 2019-04-06 05:50:51.6: In start loop, i = 0 2019-04-06 05:50:51.6: Console is now idle 2019-04-06 05:50:51.9: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:50:51.9: Script started successfully 2019-04-06 05:51:01.9: Sequencer is now active 2019-04-06 05:56:51.6: Sequencer is no longer active 2019-04-06 05:56:51.6: Post-job status check done (stat=Completed) 2019-04-06 05:56:51.6: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 05:56:51.6: ACP.RunScript(AcquireScheduler, ): 2019-04-06 05:56:51.6: Script AcquireScheduler is loaded, and 2019-04-06 05:56:51.6: the console is idle. 2019-04-06 05:56:51.6: In start loop, i = 0 2019-04-06 05:56:51.6: Console is now idle 2019-04-06 05:56:51.9: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 05:56:51.9: Script started successfully 2019-04-06 05:57:01.9: Sequencer is now active 2019-04-06 06:02:48.9: Sequencer is no longer active 2019-04-06 06:02:48.9: Post-job status check done (stat=Completed) 2019-04-06 06:02:48.9: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 06:02:48.9: ACP.RunScript(AcquireScheduler, ): 2019-04-06 06:02:48.9: Script AcquireScheduler is loaded, and 2019-04-06 06:02:48.9: the console is idle. 2019-04-06 06:02:48.9: In start loop, i = 0 2019-04-06 06:02:48.9: Console is now idle 2019-04-06 06:02:49.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 06:02:49.3: Script started successfully 2019-04-06 06:02:59.3: Sequencer is now active 2019-04-06 06:08:47.5: Sequencer is no longer active 2019-04-06 06:08:47.5: Post-job status check done (stat=Completed) 2019-04-06 06:08:47.5: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 06:08:47.5: ACP.RunScript(AcquireScheduler, ): 2019-04-06 06:08:47.5: Script AcquireScheduler is loaded, and 2019-04-06 06:08:47.5: the console is idle. 2019-04-06 06:08:47.5: In start loop, i = 0 2019-04-06 06:08:47.5: Console is now idle 2019-04-06 06:08:47.9: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 06:08:47.9: Script started successfully 2019-04-06 06:08:57.9: Sequencer is now active 2019-04-06 06:14:44.0: Sequencer is no longer active 2019-04-06 06:14:44.0: Post-job status check done (stat=Completed) 2019-04-06 06:14:44.0: Send Observation WD1145+017 to ACP Sequencer 2019-04-06 06:14:44.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 06:14:44.0: Script AcquireScheduler is loaded, and 2019-04-06 06:14:44.0: the console is idle. 2019-04-06 06:14:44.0: In start loop, i = 0 2019-04-06 06:14:44.0: Console is now idle 2019-04-06 06:14:44.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 06:14:44.3: Script started successfully 2019-04-06 06:14:54.3: Sequencer is now active 2019-04-06 06:14:59.6: Sequencer is no longer active 2019-04-06 06:15:02.6: Post-job status check done (stat=Running) 2019-04-06 06:15:02.6: ASSERT: ACP script exited with AcquireNow still True 2019-04-06 06:15:02.6: ACP ERROR: Run failed for unknown reason (see ACP run log) 2019-04-06 06:15:02.6: ACP data acquisition failed for Observation WD1145+017. 2019-04-06 06:15:02.6: (Observation WD1145+017 interrupted by acquisition process failure.) 2019-04-06 06:15:06.5: SelectRunning: No running plans 2019-04-06 06:15:06.6: EPH (136472Makemake): type=Minor Planet time=06:15:06 ra=13.0729259 dec=24.412693 2019-04-06 06:15:06.6: Obs piOri in Plan piOri was vetoed by Horizon constraint. 2019-04-06 06:15:06.6: Horizon estimates ready after 06-Apr-2019 18:35:54 UTC 2019-04-06 06:15:06.6: Obs upsOri in Plan upsOri was vetoed by Horizon constraint. 2019-04-06 06:15:06.6: Horizon estimates ready after 06-Apr-2019 20:02:54 UTC 2019-04-06 06:15:06.6: Efficiency: Only one pending plan 136472Makemake, it is the "best" 2019-04-06 06:15:06.7: Dispatcher cycle time: 0.1 sec. 2019-04-06 06:15:06.7: Acquire data for Observation 136472Makemake... 2019-04-06 06:15:06.7: (belongs to Project ASTR402_Spring2019, Plan 136472Makemake) 2019-04-06 06:15:06.7: Send Observation 136472Makemake to ACP Sequencer 2019-04-06 06:15:06.7: ACP.RunScript(AcquireScheduler, ): 2019-04-06 06:15:06.7: Script AcquireScheduler is loaded, and 2019-04-06 06:15:06.7: the console is idle. 2019-04-06 06:15:06.7: In start loop, i = 0 2019-04-06 06:15:06.7: Console is now idle 2019-04-06 06:15:06.7: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 06:15:06.7: Script started successfully 2019-04-06 06:15:16.7: Sequencer is now active 2019-04-06 06:48:54.8: Sequencer is no longer active 2019-04-06 06:48:54.8: Post-job status check done (stat=Completed) 2019-04-06 06:48:54.8: Acquisition time: 2028.1428394 sec. 2019-04-06 06:48:54.8: Data for Observation 136472Makemake of Plan 136472Makemake acquired successfully. 2019-04-06 06:48:54.9: Image Efficiency: 88.7% 2019-04-06 06:48:54.9: Cycle Efficiency: 100.0% 2019-04-06 06:48:55.9: ++ Auto Focus ++ 2019-04-06 06:48:56.0: Doing periodic autofocus. 2019-04-06 06:48:56.0: Start special ACP AutoFocus script for scheduler 2019-04-06 06:48:56.0: ACP.RunScript(AutoFocusScheduler, ): 2019-04-06 06:48:56.0: Script AcquireScheduler is loaded, and 2019-04-06 06:48:56.0: the console is idle. 2019-04-06 06:48:56.0: In start loop, i = 0 2019-04-06 06:48:56.0: Console is now idle 2019-04-06 06:48:56.0: Loaded C:\Program Files\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-04-06 06:48:56.0: Script started successfully 2019-04-06 06:49:06.0: Sequencer is now active 2019-04-06 06:53:02.9: Sequencer is no longer active 2019-04-06 06:53:02.9: Next periodic autofocus ASAP after 06-Apr-2019 09:52:45 UTC 2019-04-06 06:53:03.9: SelectRunning: Selecting from 1 running plans: 2019-04-06 06:53:03.9: Plan 136472Makemake next Obs is 136472Makemake_follow 2019-04-06 06:53:03.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 06:53:03.9: Acquire data for Observation 136472Makemake_follow... 2019-04-06 06:53:03.9: (belongs to Project ASTR402_Spring2019, Plan 136472Makemake) 2019-04-06 06:53:03.9: Doing 5 sets 2019-04-06 06:53:06.1: Send Observation 136472Makemake_follow to ACP Sequencer 2019-04-06 06:53:06.1: ACP.RunScript(AcquireScheduler, ): 2019-04-06 06:53:06.1: Script AutoFocusScheduler is loaded, and 2019-04-06 06:53:06.1: the console is idle. 2019-04-06 06:53:06.1: In start loop, i = 0 2019-04-06 06:53:06.1: Console is now idle 2019-04-06 06:53:06.1: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 06:53:06.1: Script started successfully 2019-04-06 06:53:16.1: Sequencer is now active 2019-04-06 07:05:19.7: Sequencer is no longer active 2019-04-06 07:05:19.7: Post-job status check done (stat=Completed) 2019-04-06 07:05:19.7: Send Observation 136472Makemake_follow to ACP Sequencer 2019-04-06 07:05:19.7: ACP.RunScript(AcquireScheduler, ): 2019-04-06 07:05:19.7: Script AcquireScheduler is loaded, and 2019-04-06 07:05:19.7: the console is idle. 2019-04-06 07:05:19.7: In start loop, i = 0 2019-04-06 07:05:19.7: Console is now idle 2019-04-06 07:05:20.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 07:05:20.0: Script started successfully 2019-04-06 07:05:30.0: Sequencer is now active 2019-04-06 07:16:17.2: Sequencer is no longer active 2019-04-06 07:16:17.2: Post-job status check done (stat=Completed) 2019-04-06 07:16:17.2: Send Observation 136472Makemake_follow to ACP Sequencer 2019-04-06 07:16:17.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 07:16:17.2: Script AcquireScheduler is loaded, and 2019-04-06 07:16:17.2: the console is idle. 2019-04-06 07:16:17.2: In start loop, i = 0 2019-04-06 07:16:17.2: Console is now idle 2019-04-06 07:16:17.6: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 07:16:17.6: Script started successfully 2019-04-06 07:16:27.6: Sequencer is now active 2019-04-06 07:27:28.9: Sequencer is no longer active 2019-04-06 07:27:28.9: Post-job status check done (stat=Completed) 2019-04-06 07:27:28.9: Send Observation 136472Makemake_follow to ACP Sequencer 2019-04-06 07:27:28.9: ACP.RunScript(AcquireScheduler, ): 2019-04-06 07:27:28.9: Script AcquireScheduler is loaded, and 2019-04-06 07:27:28.9: the console is idle. 2019-04-06 07:27:28.9: In start loop, i = 0 2019-04-06 07:27:28.9: Console is now idle 2019-04-06 07:27:29.3: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 07:27:29.3: Script started successfully 2019-04-06 07:27:39.3: Sequencer is now active 2019-04-06 07:38:24.6: Sequencer is no longer active 2019-04-06 07:38:24.6: Post-job status check done (stat=Completed) 2019-04-06 07:38:24.6: Send Observation 136472Makemake_follow to ACP Sequencer 2019-04-06 07:38:24.6: ACP.RunScript(AcquireScheduler, ): 2019-04-06 07:38:24.6: Script AcquireScheduler is loaded, and 2019-04-06 07:38:24.6: the console is idle. 2019-04-06 07:38:24.6: In start loop, i = 0 2019-04-06 07:38:24.6: Console is now idle 2019-04-06 07:38:25.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 07:38:25.0: Script started successfully 2019-04-06 07:38:35.0: Sequencer is now active 2019-04-06 07:49:25.8: Sequencer is no longer active 2019-04-06 07:49:25.8: Post-job status check done (stat=Completed) 2019-04-06 07:49:25.8: Acquisition time: 3379.6647315 sec. 2019-04-06 07:49:25.8: Data for Observation 136472Makemake_follow of Plan 136472Makemake acquired successfully. 2019-04-06 07:49:25.8: Plan 136472Makemake completed successfully. 2019-04-06 07:49:25.8: Image Efficiency: 88.8% 2019-04-06 07:49:25.8: Cycle Efficiency: 100.0% 2019-04-06 07:49:27.1: SelectRunning: No running plans 2019-04-06 07:49:27.1: No pending plans that can be started now. 2019-04-06 07:49:27.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:49:57.2: SelectRunning: No running plans 2019-04-06 07:49:57.2: No pending plans that can be started now. 2019-04-06 07:49:57.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:50:27.3: SelectRunning: No running plans 2019-04-06 07:50:27.3: No pending plans that can be started now. 2019-04-06 07:50:27.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:50:57.4: SelectRunning: No running plans 2019-04-06 07:50:57.4: No pending plans that can be started now. 2019-04-06 07:50:57.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:51:27.9: SelectRunning: No running plans 2019-04-06 07:51:27.9: No pending plans that can be started now. 2019-04-06 07:51:27.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:51:58.0: SelectRunning: No running plans 2019-04-06 07:51:58.0: No pending plans that can be started now. 2019-04-06 07:51:58.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:52:29.5: SelectRunning: No running plans 2019-04-06 07:52:29.5: No pending plans that can be started now. 2019-04-06 07:52:29.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:53:00.5: SelectRunning: No running plans 2019-04-06 07:53:00.5: No pending plans that can be started now. 2019-04-06 07:53:00.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:53:34.0: SelectRunning: No running plans 2019-04-06 07:53:34.0: No pending plans that can be started now. 2019-04-06 07:53:34.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:54:05.4: SelectRunning: No running plans 2019-04-06 07:54:05.4: No pending plans that can be started now. 2019-04-06 07:54:05.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:54:37.1: SelectRunning: No running plans 2019-04-06 07:54:37.1: No pending plans that can be started now. 2019-04-06 07:54:37.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:55:07.5: SelectRunning: No running plans 2019-04-06 07:55:07.5: No pending plans that can be started now. 2019-04-06 07:55:07.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:55:37.6: SelectRunning: No running plans 2019-04-06 07:55:37.6: No pending plans that can be started now. 2019-04-06 07:55:37.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:56:07.9: SelectRunning: No running plans 2019-04-06 07:56:07.9: No pending plans that can be started now. 2019-04-06 07:56:07.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:56:39.8: SelectRunning: No running plans 2019-04-06 07:56:39.8: No pending plans that can be started now. 2019-04-06 07:56:39.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:57:10.2: SelectRunning: No running plans 2019-04-06 07:57:10.2: No pending plans that can be started now. 2019-04-06 07:57:10.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:57:40.3: SelectRunning: No running plans 2019-04-06 07:57:40.3: No pending plans that can be started now. 2019-04-06 07:57:40.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:58:10.4: SelectRunning: No running plans 2019-04-06 07:58:10.4: No pending plans that can be started now. 2019-04-06 07:58:10.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:58:42.4: SelectRunning: No running plans 2019-04-06 07:58:42.4: No pending plans that can be started now. 2019-04-06 07:58:42.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:59:13.8: SelectRunning: No running plans 2019-04-06 07:59:13.8: No pending plans that can be started now. 2019-04-06 07:59:13.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 07:59:45.3: SelectRunning: No running plans 2019-04-06 07:59:45.3: No pending plans that can be started now. 2019-04-06 07:59:45.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:00:16.8: SelectRunning: No running plans 2019-04-06 08:00:16.8: No pending plans that can be started now. 2019-04-06 08:00:16.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:00:47.0: SelectRunning: No running plans 2019-04-06 08:00:47.0: No pending plans that can be started now. 2019-04-06 08:00:47.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:01:17.1: SelectRunning: No running plans 2019-04-06 08:01:17.1: No pending plans that can be started now. 2019-04-06 08:01:17.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:01:47.7: SelectRunning: No running plans 2019-04-06 08:01:47.7: No pending plans that can be started now. 2019-04-06 08:01:47.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:02:19.1: SelectRunning: No running plans 2019-04-06 08:02:19.1: No pending plans that can be started now. 2019-04-06 08:02:19.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:02:50.6: SelectRunning: No running plans 2019-04-06 08:02:50.6: No pending plans that can be started now. 2019-04-06 08:02:50.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:03:21.4: SelectRunning: No running plans 2019-04-06 08:03:21.4: No pending plans that can be started now. 2019-04-06 08:03:21.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:03:52.7: SelectRunning: No running plans 2019-04-06 08:03:52.7: No pending plans that can be started now. 2019-04-06 08:03:52.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:04:22.8: SelectRunning: No running plans 2019-04-06 08:04:22.8: No pending plans that can be started now. 2019-04-06 08:04:22.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:04:53.2: SelectRunning: No running plans 2019-04-06 08:04:53.2: No pending plans that can be started now. 2019-04-06 08:04:53.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:05:23.4: SelectRunning: No running plans 2019-04-06 08:05:23.4: No pending plans that can be started now. 2019-04-06 08:05:23.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:05:54.4: SelectRunning: No running plans 2019-04-06 08:05:54.4: No pending plans that can be started now. 2019-04-06 08:05:54.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:06:24.5: SelectRunning: No running plans 2019-04-06 08:06:24.5: No pending plans that can be started now. 2019-04-06 08:06:24.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:06:54.7: SelectRunning: No running plans 2019-04-06 08:06:54.7: No pending plans that can be started now. 2019-04-06 08:06:54.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:07:24.9: SelectRunning: No running plans 2019-04-06 08:07:24.9: No pending plans that can be started now. 2019-04-06 08:07:24.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:07:55.0: SelectRunning: No running plans 2019-04-06 08:07:55.0: No pending plans that can be started now. 2019-04-06 08:07:55.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:08:25.1: SelectRunning: No running plans 2019-04-06 08:08:25.1: No pending plans that can be started now. 2019-04-06 08:08:25.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:08:59.0: SelectRunning: No running plans 2019-04-06 08:08:59.0: No pending plans that can be started now. 2019-04-06 08:08:59.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:09:29.7: SelectRunning: No running plans 2019-04-06 08:09:29.7: No pending plans that can be started now. 2019-04-06 08:09:29.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:10:02.1: SelectRunning: No running plans 2019-04-06 08:10:02.1: No pending plans that can be started now. 2019-04-06 08:10:02.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:10:32.7: SelectRunning: No running plans 2019-04-06 08:10:32.7: No pending plans that can be started now. 2019-04-06 08:10:32.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:11:02.9: SelectRunning: No running plans 2019-04-06 08:11:02.9: No pending plans that can be started now. 2019-04-06 08:11:02.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:11:33.0: SelectRunning: No running plans 2019-04-06 08:11:33.0: No pending plans that can be started now. 2019-04-06 08:11:33.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:12:03.4: SelectRunning: No running plans 2019-04-06 08:12:03.4: No pending plans that can be started now. 2019-04-06 08:12:03.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:12:37.1: SelectRunning: No running plans 2019-04-06 08:12:37.1: No pending plans that can be started now. 2019-04-06 08:12:37.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:13:07.6: SelectRunning: No running plans 2019-04-06 08:13:07.6: No pending plans that can be started now. 2019-04-06 08:13:07.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:13:37.7: SelectRunning: No running plans 2019-04-06 08:13:37.7: No pending plans that can be started now. 2019-04-06 08:13:37.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:14:09.2: SelectRunning: No running plans 2019-04-06 08:14:09.2: No pending plans that can be started now. 2019-04-06 08:14:09.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:14:39.6: SelectRunning: No running plans 2019-04-06 08:14:39.6: No pending plans that can be started now. 2019-04-06 08:14:39.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:15:09.7: SelectRunning: No running plans 2019-04-06 08:15:09.7: No pending plans that can be started now. 2019-04-06 08:15:09.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:15:40.1: SelectRunning: No running plans 2019-04-06 08:15:40.1: No pending plans that can be started now. 2019-04-06 08:15:40.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:16:12.0: SelectRunning: No running plans 2019-04-06 08:16:12.0: No pending plans that can be started now. 2019-04-06 08:16:12.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:16:42.1: SelectRunning: No running plans 2019-04-06 08:16:42.1: No pending plans that can be started now. 2019-04-06 08:16:42.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:17:12.6: SelectRunning: No running plans 2019-04-06 08:17:12.6: No pending plans that can be started now. 2019-04-06 08:17:12.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:17:45.3: SelectRunning: No running plans 2019-04-06 08:17:45.3: No pending plans that can be started now. 2019-04-06 08:17:45.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:18:18.8: SelectRunning: No running plans 2019-04-06 08:18:18.8: No pending plans that can be started now. 2019-04-06 08:18:18.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:18:50.4: SelectRunning: No running plans 2019-04-06 08:18:50.4: No pending plans that can be started now. 2019-04-06 08:18:50.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:19:21.9: SelectRunning: No running plans 2019-04-06 08:19:21.9: No pending plans that can be started now. 2019-04-06 08:19:21.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:19:52.2: SelectRunning: No running plans 2019-04-06 08:19:52.2: No pending plans that can be started now. 2019-04-06 08:19:52.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:20:23.6: SelectRunning: No running plans 2019-04-06 08:20:23.6: No pending plans that can be started now. 2019-04-06 08:20:23.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:20:53.7: SelectRunning: No running plans 2019-04-06 08:20:53.7: No pending plans that can be started now. 2019-04-06 08:20:53.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:21:27.7: SelectRunning: No running plans 2019-04-06 08:21:27.7: No pending plans that can be started now. 2019-04-06 08:21:27.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:21:59.0: SelectRunning: No running plans 2019-04-06 08:21:59.0: No pending plans that can be started now. 2019-04-06 08:21:59.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:22:30.8: SelectRunning: No running plans 2019-04-06 08:22:30.8: No pending plans that can be started now. 2019-04-06 08:22:30.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:23:01.2: SelectRunning: No running plans 2019-04-06 08:23:01.2: No pending plans that can be started now. 2019-04-06 08:23:01.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:23:31.7: SelectRunning: No running plans 2019-04-06 08:23:31.7: No pending plans that can be started now. 2019-04-06 08:23:31.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:24:01.8: SelectRunning: No running plans 2019-04-06 08:24:01.8: No pending plans that can be started now. 2019-04-06 08:24:01.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:24:31.9: SelectRunning: No running plans 2019-04-06 08:24:31.9: No pending plans that can be started now. 2019-04-06 08:24:31.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:25:02.4: SelectRunning: No running plans 2019-04-06 08:25:02.4: No pending plans that can be started now. 2019-04-06 08:25:02.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:25:32.8: SelectRunning: No running plans 2019-04-06 08:25:32.8: No pending plans that can be started now. 2019-04-06 08:25:32.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:26:04.4: SelectRunning: No running plans 2019-04-06 08:26:04.4: No pending plans that can be started now. 2019-04-06 08:26:04.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:26:35.9: SelectRunning: No running plans 2019-04-06 08:26:35.9: No pending plans that can be started now. 2019-04-06 08:26:35.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:27:07.6: SelectRunning: No running plans 2019-04-06 08:27:07.6: No pending plans that can be started now. 2019-04-06 08:27:07.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:27:38.0: SelectRunning: No running plans 2019-04-06 08:27:38.0: No pending plans that can be started now. 2019-04-06 08:27:38.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:28:08.2: SelectRunning: No running plans 2019-04-06 08:28:08.2: No pending plans that can be started now. 2019-04-06 08:28:08.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:28:41.7: SelectRunning: No running plans 2019-04-06 08:28:41.7: No pending plans that can be started now. 2019-04-06 08:28:41.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:29:13.8: SelectRunning: No running plans 2019-04-06 08:29:13.8: No pending plans that can be started now. 2019-04-06 08:29:13.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:29:43.9: SelectRunning: No running plans 2019-04-06 08:29:43.9: No pending plans that can be started now. 2019-04-06 08:29:43.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:30:14.1: SelectRunning: No running plans 2019-04-06 08:30:14.1: No pending plans that can be started now. 2019-04-06 08:30:14.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:30:45.3: SelectRunning: No running plans 2019-04-06 08:30:45.3: No pending plans that can be started now. 2019-04-06 08:30:45.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:31:16.8: SelectRunning: No running plans 2019-04-06 08:31:16.8: No pending plans that can be started now. 2019-04-06 08:31:16.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:31:47.2: SelectRunning: No running plans 2019-04-06 08:31:47.2: No pending plans that can be started now. 2019-04-06 08:31:47.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:32:17.7: SelectRunning: No running plans 2019-04-06 08:32:17.7: No pending plans that can be started now. 2019-04-06 08:32:17.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:32:47.9: SelectRunning: No running plans 2019-04-06 08:32:47.9: No pending plans that can be started now. 2019-04-06 08:32:47.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:33:18.3: SelectRunning: No running plans 2019-04-06 08:33:18.3: No pending plans that can be started now. 2019-04-06 08:33:18.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:33:50.0: SelectRunning: No running plans 2019-04-06 08:33:50.0: No pending plans that can be started now. 2019-04-06 08:33:50.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:34:21.4: SelectRunning: No running plans 2019-04-06 08:34:21.4: No pending plans that can be started now. 2019-04-06 08:34:21.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:34:54.9: SelectRunning: No running plans 2019-04-06 08:34:54.9: No pending plans that can be started now. 2019-04-06 08:34:54.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:35:26.7: SelectRunning: No running plans 2019-04-06 08:35:26.7: No pending plans that can be started now. 2019-04-06 08:35:26.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:35:57.6: SelectRunning: No running plans 2019-04-06 08:35:57.6: No pending plans that can be started now. 2019-04-06 08:35:57.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:36:30.0: SelectRunning: No running plans 2019-04-06 08:36:30.0: No pending plans that can be started now. 2019-04-06 08:36:30.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:37:00.1: SelectRunning: No running plans 2019-04-06 08:37:00.1: No pending plans that can be started now. 2019-04-06 08:37:00.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:37:30.6: SelectRunning: No running plans 2019-04-06 08:37:30.6: No pending plans that can be started now. 2019-04-06 08:37:30.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:38:00.7: SelectRunning: No running plans 2019-04-06 08:38:00.7: No pending plans that can be started now. 2019-04-06 08:38:00.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:38:30.8: SelectRunning: No running plans 2019-04-06 08:38:30.8: No pending plans that can be started now. 2019-04-06 08:38:30.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:39:02.6: SelectRunning: No running plans 2019-04-06 08:39:02.6: No pending plans that can be started now. 2019-04-06 08:39:02.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:39:33.0: SelectRunning: No running plans 2019-04-06 08:39:33.0: No pending plans that can be started now. 2019-04-06 08:39:33.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:40:03.4: SelectRunning: No running plans 2019-04-06 08:40:03.4: No pending plans that can be started now. 2019-04-06 08:40:03.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:40:34.6: SelectRunning: No running plans 2019-04-06 08:40:34.6: No pending plans that can be started now. 2019-04-06 08:40:34.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:41:04.8: SelectRunning: No running plans 2019-04-06 08:41:04.8: No pending plans that can be started now. 2019-04-06 08:41:04.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:41:37.0: SelectRunning: No running plans 2019-04-06 08:41:37.0: No pending plans that can be started now. 2019-04-06 08:41:37.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:42:07.2: SelectRunning: No running plans 2019-04-06 08:42:07.2: No pending plans that can be started now. 2019-04-06 08:42:07.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:42:38.4: SelectRunning: No running plans 2019-04-06 08:42:38.4: No pending plans that can be started now. 2019-04-06 08:42:38.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:43:08.8: SelectRunning: No running plans 2019-04-06 08:43:08.8: No pending plans that can be started now. 2019-04-06 08:43:08.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:43:40.4: SelectRunning: No running plans 2019-04-06 08:43:40.4: No pending plans that can be started now. 2019-04-06 08:43:40.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:44:10.5: SelectRunning: No running plans 2019-04-06 08:44:10.5: No pending plans that can be started now. 2019-04-06 08:44:10.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:44:40.7: SelectRunning: No running plans 2019-04-06 08:44:40.7: No pending plans that can be started now. 2019-04-06 08:44:40.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:45:12.6: SelectRunning: No running plans 2019-04-06 08:45:12.6: No pending plans that can be started now. 2019-04-06 08:45:12.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:45:42.7: SelectRunning: No running plans 2019-04-06 08:45:42.7: No pending plans that can be started now. 2019-04-06 08:45:42.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:46:13.0: SelectRunning: No running plans 2019-04-06 08:46:13.0: No pending plans that can be started now. 2019-04-06 08:46:13.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:46:44.9: SelectRunning: No running plans 2019-04-06 08:46:44.9: No pending plans that can be started now. 2019-04-06 08:46:44.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:47:15.0: SelectRunning: No running plans 2019-04-06 08:47:15.0: No pending plans that can be started now. 2019-04-06 08:47:15.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:47:45.5: SelectRunning: No running plans 2019-04-06 08:47:45.5: No pending plans that can be started now. 2019-04-06 08:47:45.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:48:18.6: SelectRunning: No running plans 2019-04-06 08:48:18.6: No pending plans that can be started now. 2019-04-06 08:48:18.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:48:50.6: SelectRunning: No running plans 2019-04-06 08:48:50.6: No pending plans that can be started now. 2019-04-06 08:48:50.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:49:20.8: SelectRunning: No running plans 2019-04-06 08:49:20.8: No pending plans that can be started now. 2019-04-06 08:49:20.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:49:51.6: SelectRunning: No running plans 2019-04-06 08:49:51.6: No pending plans that can be started now. 2019-04-06 08:49:51.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:50:22.7: SelectRunning: No running plans 2019-04-06 08:50:22.7: No pending plans that can be started now. 2019-04-06 08:50:22.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:50:52.8: SelectRunning: No running plans 2019-04-06 08:50:52.8: No pending plans that can be started now. 2019-04-06 08:50:52.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:51:24.8: SelectRunning: No running plans 2019-04-06 08:51:24.8: No pending plans that can be started now. 2019-04-06 08:51:24.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:51:56.8: SelectRunning: No running plans 2019-04-06 08:51:56.8: No pending plans that can be started now. 2019-04-06 08:51:56.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:52:27.3: SelectRunning: No running plans 2019-04-06 08:52:27.3: No pending plans that can be started now. 2019-04-06 08:52:27.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:52:57.6: SelectRunning: No running plans 2019-04-06 08:52:57.6: No pending plans that can be started now. 2019-04-06 08:52:57.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:53:28.6: SelectRunning: No running plans 2019-04-06 08:53:28.6: No pending plans that can be started now. 2019-04-06 08:53:28.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:53:58.9: SelectRunning: No running plans 2019-04-06 08:53:58.9: No pending plans that can be started now. 2019-04-06 08:53:58.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:54:30.5: SelectRunning: No running plans 2019-04-06 08:54:30.5: No pending plans that can be started now. 2019-04-06 08:54:30.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:55:00.6: SelectRunning: No running plans 2019-04-06 08:55:00.6: No pending plans that can be started now. 2019-04-06 08:55:00.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:55:30.7: SelectRunning: No running plans 2019-04-06 08:55:30.7: No pending plans that can be started now. 2019-04-06 08:55:30.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:56:01.8: SelectRunning: No running plans 2019-04-06 08:56:01.8: No pending plans that can be started now. 2019-04-06 08:56:01.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:56:33.0: SelectRunning: No running plans 2019-04-06 08:56:33.0: No pending plans that can be started now. 2019-04-06 08:56:33.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:57:03.2: SelectRunning: No running plans 2019-04-06 08:57:03.2: No pending plans that can be started now. 2019-04-06 08:57:03.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:57:33.3: SelectRunning: No running plans 2019-04-06 08:57:33.3: No pending plans that can be started now. 2019-04-06 08:57:33.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:58:03.4: SelectRunning: No running plans 2019-04-06 08:58:03.4: No pending plans that can be started now. 2019-04-06 08:58:03.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:58:34.7: SelectRunning: No running plans 2019-04-06 08:58:34.7: No pending plans that can be started now. 2019-04-06 08:58:34.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:59:06.1: SelectRunning: No running plans 2019-04-06 08:59:06.1: No pending plans that can be started now. 2019-04-06 08:59:06.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 08:59:38.3: SelectRunning: No running plans 2019-04-06 08:59:38.3: No pending plans that can be started now. 2019-04-06 08:59:38.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:00:08.8: SelectRunning: No running plans 2019-04-06 09:00:08.8: No pending plans that can be started now. 2019-04-06 09:00:08.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:00:40.7: SelectRunning: No running plans 2019-04-06 09:00:40.7: No pending plans that can be started now. 2019-04-06 09:00:40.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:01:10.8: SelectRunning: No running plans 2019-04-06 09:01:10.8: No pending plans that can be started now. 2019-04-06 09:01:10.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:01:41.2: SelectRunning: No running plans 2019-04-06 09:01:41.2: No pending plans that can be started now. 2019-04-06 09:01:41.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:02:13.0: SelectRunning: No running plans 2019-04-06 09:02:13.1: No pending plans that can be started now. 2019-04-06 09:02:13.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:02:43.5: SelectRunning: No running plans 2019-04-06 09:02:43.5: No pending plans that can be started now. 2019-04-06 09:02:43.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:03:13.6: SelectRunning: No running plans 2019-04-06 09:03:13.6: No pending plans that can be started now. 2019-04-06 09:03:13.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:03:43.9: SelectRunning: No running plans 2019-04-06 09:03:43.9: No pending plans that can be started now. 2019-04-06 09:03:43.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:04:14.0: SelectRunning: No running plans 2019-04-06 09:04:14.0: No pending plans that can be started now. 2019-04-06 09:04:14.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:04:44.1: SelectRunning: No running plans 2019-04-06 09:04:44.1: No pending plans that can be started now. 2019-04-06 09:04:44.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:05:15.3: SelectRunning: No running plans 2019-04-06 09:05:15.3: No pending plans that can be started now. 2019-04-06 09:05:15.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:05:45.6: SelectRunning: No running plans 2019-04-06 09:05:45.6: No pending plans that can be started now. 2019-04-06 09:05:45.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:06:15.7: SelectRunning: No running plans 2019-04-06 09:06:15.7: No pending plans that can be started now. 2019-04-06 09:06:15.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:06:45.9: SelectRunning: No running plans 2019-04-06 09:06:45.9: No pending plans that can be started now. 2019-04-06 09:06:45.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:07:17.5: SelectRunning: No running plans 2019-04-06 09:07:17.5: No pending plans that can be started now. 2019-04-06 09:07:17.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:07:47.6: SelectRunning: No running plans 2019-04-06 09:07:47.6: No pending plans that can be started now. 2019-04-06 09:07:47.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:08:17.7: SelectRunning: No running plans 2019-04-06 09:08:17.7: No pending plans that can be started now. 2019-04-06 09:08:17.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:08:48.4: SelectRunning: No running plans 2019-04-06 09:08:48.4: No pending plans that can be started now. 2019-04-06 09:08:48.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:09:19.3: SelectRunning: No running plans 2019-04-06 09:09:19.3: No pending plans that can be started now. 2019-04-06 09:09:19.3: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:09:49.4: SelectRunning: No running plans 2019-04-06 09:09:49.4: No pending plans that can be started now. 2019-04-06 09:09:49.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:10:19.5: SelectRunning: No running plans 2019-04-06 09:10:19.5: No pending plans that can be started now. 2019-04-06 09:10:19.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:10:52.9: SelectRunning: No running plans 2019-04-06 09:10:52.9: No pending plans that can be started now. 2019-04-06 09:10:52.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:11:23.0: SelectRunning: No running plans 2019-04-06 09:11:23.0: No pending plans that can be started now. 2019-04-06 09:11:23.0: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:11:53.1: SelectRunning: No running plans 2019-04-06 09:11:53.1: No pending plans that can be started now. 2019-04-06 09:11:53.1: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:12:23.2: SelectRunning: No running plans 2019-04-06 09:12:23.2: No pending plans that can be started now. 2019-04-06 09:12:23.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:12:53.6: SelectRunning: No running plans 2019-04-06 09:12:53.6: No pending plans that can be started now. 2019-04-06 09:12:53.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:13:23.7: SelectRunning: No running plans 2019-04-06 09:13:23.7: No pending plans that can be started now. 2019-04-06 09:13:23.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:13:55.5: SelectRunning: No running plans 2019-04-06 09:13:55.5: No pending plans that can be started now. 2019-04-06 09:13:55.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:14:26.9: SelectRunning: No running plans 2019-04-06 09:14:26.9: No pending plans that can be started now. 2019-04-06 09:14:26.9: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:15:00.4: SelectRunning: No running plans 2019-04-06 09:15:00.4: No pending plans that can be started now. 2019-04-06 09:15:00.4: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:15:30.5: SelectRunning: No running plans 2019-04-06 09:15:30.5: No pending plans that can be started now. 2019-04-06 09:15:30.5: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:16:00.6: SelectRunning: No running plans 2019-04-06 09:16:00.6: No pending plans that can be started now. 2019-04-06 09:16:00.6: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:16:32.7: SelectRunning: No running plans 2019-04-06 09:16:32.7: No pending plans that can be started now. 2019-04-06 09:16:32.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:17:04.2: SelectRunning: No running plans 2019-04-06 09:17:04.2: No pending plans that can be started now. 2019-04-06 09:17:04.2: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:17:37.7: SelectRunning: No running plans 2019-04-06 09:17:37.7: No pending plans that can be started now. 2019-04-06 09:17:37.7: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:18:07.8: SelectRunning: No running plans 2019-04-06 09:18:07.8: No pending plans that can be started now. 2019-04-06 09:18:07.8: Dispatcher cycle time: 0.0 sec. 2019-04-06 09:18:37.9: SelectRunning: No running plans 2019-04-06 09:18:37.9: EPH (Jupiter): type=Major Planet time=09:18:37 ra=17.5703386 dec=-22.670150 2019-04-06 09:18:37.9: Efficiency: Selecting from 2 pending plans: 2019-04-06 09:18:37.9: Selected Plan Jup (TotEff=1.48) 2019-04-06 09:18:38.0: Dispatcher cycle time: 0.1 sec. 2019-04-06 09:18:38.0: Acquire data for Observation Jup... 2019-04-06 09:18:38.0: (belongs to Project ASTR402_Spring2019, Plan Jup) 2019-04-06 09:18:38.0: Doing 5 sets 2019-04-06 09:18:38.0: Send Observation Jup to ACP Sequencer 2019-04-06 09:18:38.0: ACP.RunScript(AcquireScheduler, ): 2019-04-06 09:18:38.0: Script AcquireScheduler is loaded, and 2019-04-06 09:18:38.0: the console is idle. 2019-04-06 09:18:38.0: In start loop, i = 0 2019-04-06 09:18:38.0: Console is now idle 2019-04-06 09:18:38.0: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 09:18:38.0: Script started successfully 2019-04-06 09:18:48.0: Sequencer is now active 2019-04-06 09:25:34.4: Sequencer is no longer active 2019-04-06 09:25:34.4: Post-job status check done (stat=Completed) 2019-04-06 09:25:34.4: Send Observation Jup to ACP Sequencer 2019-04-06 09:25:34.4: ACP.RunScript(AcquireScheduler, ): 2019-04-06 09:25:34.4: Script AcquireScheduler is loaded, and 2019-04-06 09:25:34.4: the console is idle. 2019-04-06 09:25:34.4: In start loop, i = 0 2019-04-06 09:25:34.4: Console is now idle 2019-04-06 09:25:34.8: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 09:25:34.8: Script started successfully 2019-04-06 09:25:44.8: Sequencer is now active 2019-04-06 09:29:38.1: Sequencer is no longer active 2019-04-06 09:29:38.1: Post-job status check done (stat=Completed) 2019-04-06 09:29:38.2: Send Observation Jup to ACP Sequencer 2019-04-06 09:29:38.2: ACP.RunScript(AcquireScheduler, ): 2019-04-06 09:29:38.2: Script AcquireScheduler is loaded, and 2019-04-06 09:29:38.2: the console is idle. 2019-04-06 09:29:38.2: In start loop, i = 0 2019-04-06 09:29:38.2: Console is now idle 2019-04-06 09:29:38.5: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 09:29:38.5: Script started successfully 2019-04-06 09:29:48.5: Sequencer is now active 2019-04-06 09:33:22.8: Sequencer is no longer active 2019-04-06 09:33:22.8: Post-job status check done (stat=Completed) 2019-04-06 09:33:22.8: Send Observation Jup to ACP Sequencer 2019-04-06 09:33:22.8: ACP.RunScript(AcquireScheduler, ): 2019-04-06 09:33:22.8: Script AcquireScheduler is loaded, and 2019-04-06 09:33:22.8: the console is idle. 2019-04-06 09:33:22.8: In start loop, i = 0 2019-04-06 09:33:22.8: Console is now idle 2019-04-06 09:33:23.2: Loaded C:\Program Files\ACP Scheduler\AcquireScheduler.vbs successfully 2019-04-06 09:33:23.2: Script started successfully 2019-04-06 09:33:33.2: Sequencer is now active Log level changed from Debug to Verbose Log level changed from Verbose to Brief 2019-04-06 10:48:31.0: Dispatcher stopped at 06-Apr-2019 10:48:31 UTC 2019-04-06 10:48:31.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2019-04-06 10:48:40.2: RUN FAILED: Scheduler was stopped during run 2019-04-06 10:48:40.2: ACP data acquisition failed for Observation Jup. 2019-04-06 10:48:40.2: (Observation Jup interrupted by operator or system.) 2019-04-06 10:49:09.2: Dispatcher started at 06-Apr-2019 10:49:09 UTC 2019-04-06 10:49:10.0: ++ Observatory Startup ++ 2019-04-06 10:49:10.0: Starting with open dome, unsafe weather will cause operator intervention error 2019-04-06 10:49:30.1: Observatory startup succeeded 2019-04-06 10:49:31.2: ++ Auto Focus ++ 2019-04-06 10:53:36.5: Dispatcher cycle time: 0.1 sec. 2019-04-06 10:53:36.5: Acquire data for Observation Jupiter... 2019-04-06 10:53:36.5: (belongs to Project ASTR402_Spring2019, Plan Jupiter) 2019-04-06 10:53:36.5: Doing 5 sets 2019-04-06 11:03:35.6: Dispatcher stopped at 06-Apr-2019 11:03:35 UTC 2019-04-06 11:03:35.6: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2019-04-06 11:03:41.6: ** ACP script "AcquireScheduler" failed: 2019-04-06 11:03:41.6: **Script Error** Source: ACP Message: The script was aborted. Location: line 912 column 5. 2019-04-06 11:03:44.6: RUN FAILED: Scheduler was stopped during run 2019-04-06 11:03:44.6: ACP data acquisition failed for Observation Jupiter. 2019-04-06 11:03:44.6: (Observation Jupiter interrupted by operator or system.) 2019-04-06 11:04:20.0: Dispatcher started at 06-Apr-2019 11:04:20 UTC 2019-04-06 11:04:22.3: ++ Observatory Startup ++ 2019-04-06 11:04:22.3: Starting with open dome, unsafe weather will cause operator intervention error 2019-04-06 11:04:45.4: Observatory startup succeeded 2019-04-06 11:04:45.4: ++ Auto Focus ++ 2019-04-06 11:08:30.2: Dispatcher cycle time: 0.2 sec. 2019-04-06 11:08:30.2: Acquire data for Observation Jup... 2019-04-06 11:08:30.2: (belongs to Project ASTR402_Spring2019, Plan Jup) 2019-04-06 11:08:30.2: Doing 5 sets 2019-04-06 11:13:52.8: Dispatcher stopped at 06-Apr-2019 11:13:52 UTC 2019-04-06 11:13:52.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2019-04-06 11:13:59.2: ** ACP script "AcquireScheduler" failed: 2019-04-06 11:13:59.2: **Script Error** Source: ACP Observatory Control Software Message: The script was interrupted before completion. Location: line 1381 column 13. 2019-04-06 11:14:02.2: RUN FAILED: Scheduler was stopped during run 2019-04-06 11:14:02.2: ACP data acquisition failed for Observation Jup. 2019-04-06 11:14:02.2: (Observation Jup interrupted by operator or system.) 2019-04-06 11:17:44.5: Dispatcher started at 06-Apr-2019 11:17:44 UTC 2019-04-06 11:17:45.4: ++ Observatory Startup ++ 2019-04-06 11:19:15.8: Observatory startup succeeded, and dome opened successfully 2019-04-06 11:19:15.8: ++ Auto Focus ++ 2019-04-06 11:22:58.2: Dispatcher cycle time: 0.1 sec. 2019-04-06 11:22:58.2: Acquire data for Observation Jup... 2019-04-06 11:22:58.2: (belongs to Project ASTR402_Spring2019, Plan Jup) 2019-04-06 11:22:58.2: Doing 5 sets 2019-04-06 11:44:56.4: Acquisition time: 1316.0143425 sec. 2019-04-06 11:44:56.4: Data for Observation Jup of Plan Jup acquired successfully. 2019-04-06 11:44:56.4: Plan Jup completed successfully. 2019-04-06 11:44:56.4: Image Efficiency: 1.1% 2019-04-06 11:44:56.4: Cycle Efficiency: 100.0% 2019-04-06 12:10:14.5: ++ Dawn Flats ++ 2019-04-06 12:41:43.8: ** ACP script "AutoFlat" failed: 2019-04-06 12:41:43.8: **Script Error** Source: ACP Observatory Control Software Message: The script was interrupted before completion. Location: line 1429 column 13. 2019-04-06 12:41:44.7: -- Weather Unsafe -- 2019-04-06 12:42:16.2: -- Observatory Shutdown -- 2019-04-06 12:42:16.2: WARNING: Request sent while Weather Safety Script is running 2019-04-06 14:14:24.4: Dispatcher stopped at 06-Apr-2019 14:14:24 UTC 2019-04-06 14:14:24.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2019-04-06 19:01:28.0: Requested shutdown of scheduler 2019-04-06 19:01:28.1: Run statistics: 2019-04-06 19:01:28.1: Observations: 62 2019-04-06 19:01:28.1: Considered: 18 2019-04-06 19:01:28.1: Completed: 9 2019-04-06 19:01:28.1: Skipped: 2 2019-04-06 19:01:28.1: Never Eligible: 44 2019-04-06 19:01:28.1: Failed: 7 2019-04-06 19:01:28.1: Shutter-Open efficiency: 45.61% 2019-04-06 19:01:28.1: Overall Efficiency: 99.90% Log closed at Sat, Apr 06 2019 19:01:29 UTC (actual time)