Log opened at Fri, May 16 2014 02:56:03 UTC (actual time) Current log level is Verbose Scheduler version 3.5.3 Multi-user license: Arne Henden 16-May-2014 02:56:04.9: ++ Observatory Startup ++ 16-May-2014 02:56:04.9: Start ACP Sequencer's StartupObs script 16-May-2014 02:56:46.2: Open the observatory dome/roof 16-May-2014 02:58:06.1: ++ Auto Focus ++ 16-May-2014 02:58:06.1: Doing initial autofocus. 16-May-2014 02:58:06.1: Start special ACP AutoFocus script for scheduler 16-May-2014 03:01:13.2: Next periodic autofocus ASAP after 16-May-2014 05:00:55 UTC 16-May-2014 03:01:15.3: Plan RZ_Leo has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan PNV_J1902 has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan SN_2014G has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan SN_2014J has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan M56 has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan NGC_4147 has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan NGC_5053 has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:15.3: Plan NGC_5466 has Monitor Mode. Time to resubmit it. 16-May-2014 03:01:16.2: Dispatcher cycle time: 0.9516017 sec. 16-May-2014 03:01:16.2: Acquire data for Observation SN_2014J... 16-May-2014 03:01:16.2: (belongs to Project AAVSO_Monitoring, Plan SN_2014J) 16-May-2014 03:01:16.2: Doing 3 sets 16-May-2014 03:01:16.2: Send Observation SN_2014J to ACP Sequencer 16-May-2014 03:06:58.7: Send Observation SN_2014J to ACP Sequencer 16-May-2014 03:11:42.6: Send Observation SN_2014J to ACP Sequencer 16-May-2014 03:16:30.7: Acquisition time: 914.4736063 sec. 16-May-2014 03:16:30.7: Data for Observation SN_2014J acquired successfully. 16-May-2014 03:16:30.8: Image Efficiency: 49.2% 16-May-2014 03:16:30.8: Cycle Efficiency: 99.9% 16-May-2014 03:16:40.2: Dispatcher cycle time: 0.7020012 sec. 16-May-2014 03:16:40.2: Acquire data for Observation SN_2014G... 16-May-2014 03:16:40.2: (belongs to Project AAVSO_Monitoring, Plan SN_2014G) 16-May-2014 03:16:40.2: Send Observation SN_2014G to ACP Sequencer 16-May-2014 03:26:27.9: Acquisition time: 587.7320323 sec. 16-May-2014 03:26:27.9: Data for Observation SN_2014G acquired successfully. 16-May-2014 03:26:27.9: Image Efficiency: 71.4% 16-May-2014 03:26:27.9: Cycle Efficiency: 99.9% 16-May-2014 03:26:35.6: Dispatcher cycle time: 0.608401 sec. 16-May-2014 03:26:35.6: Acquire data for Observation RZ_Leo... 16-May-2014 03:26:35.6: (belongs to Project Walker, Plan RZ_Leo) 16-May-2014 03:26:35.6: Send Observation RZ_Leo to ACP Sequencer 16-May-2014 04:12:32.8: Acquisition time: 2757.1976429 sec. 16-May-2014 04:12:32.8: Data for Observation RZ_Leo acquired successfully. 16-May-2014 04:12:32.8: Image Efficiency: 72.5% 16-May-2014 04:12:32.8: Cycle Efficiency: 100.0% 16-May-2014 04:12:41.6: Dispatcher cycle time: 0.577201 sec. 16-May-2014 04:12:41.6: Acquire data for Observation NGC_4147... 16-May-2014 04:12:41.6: (belongs to Project Welch_gc, Plan NGC_4147) 16-May-2014 04:12:41.6: Send Observation NGC_4147 to ACP Sequencer 16-May-2014 04:36:51.0: Acquisition time: 1449.3995458 sec. 16-May-2014 04:36:51.0: Data for Observation NGC_4147 acquired successfully. 16-May-2014 04:36:51.1: Image Efficiency: 82.8% 16-May-2014 04:36:51.1: Cycle Efficiency: 100.0% 16-May-2014 04:36:56.8: Dispatcher cycle time: 0.561601 sec. 16-May-2014 04:36:56.8: Acquire data for Observation Pal_4... 16-May-2014 04:36:56.8: (belongs to Project Welch_gc, Plan Pal_4) 16-May-2014 04:36:56.8: Send Observation Pal_4 to ACP Sequencer 16-May-2014 05:16:22.8: Acquisition time: 2365.9177556 sec. 16-May-2014 05:16:22.8: Data for Observation Pal_4 acquired successfully. 16-May-2014 05:16:22.8: Image Efficiency: 84.5% 16-May-2014 05:16:22.8: Cycle Efficiency: 100.0% 16-May-2014 05:16:30.2: ++ Auto Focus ++ 16-May-2014 05:16:30.2: Doing periodic autofocus. 16-May-2014 05:16:30.2: Start special ACP AutoFocus script for scheduler 16-May-2014 05:19:24.0: Next periodic autofocus ASAP after 16-May-2014 07:18:52 UTC 16-May-2014 05:19:26.2: Dispatcher cycle time: 0.2184004 sec. 16-May-2014 05:19:26.2: Acquire data for Observation NGC_5272... 16-May-2014 05:19:26.2: (belongs to Project Welch_gc, Plan NGC_5272) 16-May-2014 05:19:26.2: Send Observation NGC_5272 to ACP Sequencer 16-May-2014 05:38:53.6: Acquisition time: 1167.3354503 sec. 16-May-2014 05:38:53.6: Data for Observation NGC_5272 acquired successfully. 16-May-2014 05:38:53.6: Image Efficiency: 77.1% 16-May-2014 05:38:53.6: Cycle Efficiency: 100.0% 16-May-2014 05:38:58.9: Dispatcher cycle time: 0.2652004 sec. 16-May-2014 05:38:58.9: Acquire data for Observation NGC_5466... 16-May-2014 05:38:58.9: (belongs to Project Welch_gc, Plan NGC_5466) 16-May-2014 05:38:58.9: Send Observation NGC_5466 to ACP Sequencer 16-May-2014 06:03:05.2: Acquisition time: 1446.2639403 sec. 16-May-2014 06:03:05.2: Data for Observation NGC_5466 acquired successfully. 16-May-2014 06:03:05.2: Image Efficiency: 83.0% 16-May-2014 06:03:05.2: Cycle Efficiency: 100.0% 16-May-2014 06:03:15.4: Dispatcher cycle time: 0.2808005 sec. 16-May-2014 06:03:15.4: Acquire data for Observation M56... 16-May-2014 06:03:15.4: (belongs to Project AAVSO_Monitoring, Plan M56) 16-May-2014 06:03:15.4: Doing 3 sets 16-May-2014 06:03:15.4: Send Observation M56 to ACP Sequencer 16-May-2014 06:09:50.7: Send Observation M56 to ACP Sequencer 16-May-2014 06:15:09.2: Send Observation M56 to ACP Sequencer 16-May-2014 06:20:18.8: Acquisition time: 1023.3939976 sec. 16-May-2014 06:20:18.8: Data for Observation M56 acquired successfully. 16-May-2014 06:20:18.9: Image Efficiency: 70.3% 16-May-2014 06:20:18.9: Cycle Efficiency: 100.0% 16-May-2014 06:20:28.6: Dispatcher cycle time: 0.608401 sec. 16-May-2014 06:20:28.6: Acquire data for Observation Pal_5... 16-May-2014 06:20:28.6: (belongs to Project Welch_gc, Plan Pal_5) 16-May-2014 06:20:28.6: Send Observation Pal_5 to ACP Sequencer 16-May-2014 06:44:59.8: Dispatcher stopped at 16-May-2014 06:44:59 UTC 16-May-2014 06:44:59.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 16-May-2014 06:45:25.1: ** Sequencer script "AcquireScheduler" failed: 16-May-2014 06:45:25.1: **Script Error** Source: ACP Message: The script was aborted. at line 1356 column 17. Script Context: 1353: ' 1354: If Len(imgFileList) > 0 Then imgFileList = imgFileList & "," 1355: If wantsCompress Then => 1356: Util.CompressFile fnf, fn & ".fts.zip" ^ 1357: FSO.DeleteFile fnf ' As requested, delete original 1358: imgFileList = imgFileList & fn & ".fts.zip" 1359: Else -- end -- 16-May-2014 06:45:28.1: RUN FAILED: Scheduler was stopped during run 16-May-2014 06:45:28.1: ACP data acquisition failed for Observation Pal_5. 16-May-2014 06:45:28.1: (Observation Pal_5 interrupted by operator or system.) 16-May-2014 06:52:08.5: Dispatcher started at 16-May-2014 06:52:08 UTC 16-May-2014 06:52:08.5: Priority: W[0]=1.00 16-May-2014 06:52:08.5: Transit Altitude: W[1]=0.70 16-May-2014 06:52:08.5: Highest Altitude: W[6]=0.00 16-May-2014 06:52:08.5: Lateness: W[5]=0.00 16-May-2014 06:52:08.5: Slew Distance: W[2]=0.30 16-May-2014 06:52:08.5: Retry Count: W[3]=0.20 16-May-2014 06:52:08.5: Meridian Crossing: W[4]=0.30 16-May-2014 06:52:08.5: Obs Conditions: W[7]=0.40 16-May-2014 06:52:08.5: Rising Plan Delay: disabled 16-May-2014 06:52:13.8: ++ Observatory Startup ++ 16-May-2014 06:52:13.8: Start ACP Sequencer's StartupObs script 16-May-2014 06:52:33.0: ++ Auto Focus ++ 16-May-2014 06:52:33.0: Doing initial autofocus. 16-May-2014 06:52:33.0: Start special ACP AutoFocus script for scheduler 16-May-2014 06:55:29.3: Next periodic autofocus ASAP after 16-May-2014 08:55:09 UTC 16-May-2014 06:55:32.2: Dispatcher cycle time: 0.7332013 sec. 16-May-2014 06:55:32.2: Acquire data for Observation PNV_J1902... 16-May-2014 06:55:32.2: (belongs to Project AAVSO_Monitoring, Plan PNV_J1902) 16-May-2014 06:55:32.2: Send Observation PNV_J1902 to ACP Sequencer 16-May-2014 07:30:33.2: Acquisition time: 2100.9980903 sec. 16-May-2014 07:30:33.2: Data for Observation PNV_J1902 acquired successfully. 16-May-2014 07:30:33.2: Image Efficiency: 66.6% 16-May-2014 07:30:33.2: Cycle Efficiency: 100.0% 16-May-2014 07:30:44.2: Dispatcher cycle time: 0.8424015 sec. 16-May-2014 07:30:44.2: Acquire data for Observation NGC_6229... 16-May-2014 07:30:44.2: (belongs to Project Welch_gc, Plan NGC_6229) 16-May-2014 07:30:44.2: Send Observation NGC_6229 to ACP Sequencer 16-May-2014 08:03:07.9: Acquisition time: 1943.733214 sec. 16-May-2014 08:03:07.9: Data for Observation NGC_6229 acquired successfully. 16-May-2014 08:03:07.9: Image Efficiency: 82.3% 16-May-2014 08:03:07.9: Cycle Efficiency: 100.0% 16-May-2014 08:03:14.6: Dispatcher cycle time: 0.8736015 sec. 16-May-2014 08:03:14.6: Acquire data for Observation V5593_Sgr... 16-May-2014 08:03:14.6: (belongs to Project Nova_Monitoring, Plan V5593_Sgr) 16-May-2014 08:03:14.6: Send Observation V5593_Sgr to ACP Sequencer 16-May-2014 08:32:46.3: Acquisition time: 1771.681512 sec. 16-May-2014 08:32:46.3: Data for Observation V5593_Sgr acquired successfully. 16-May-2014 08:32:46.3: Image Efficiency: 79.0% 16-May-2014 08:32:46.3: Cycle Efficiency: 99.9% 16-May-2014 08:32:52.7: Dispatcher cycle time: 0.9516017 sec. 16-May-2014 08:32:52.7: Acquire data for Observation V5584_Sgr... 16-May-2014 08:32:52.7: (belongs to Project Nova_Monitoring, Plan V5584_Sgr) 16-May-2014 08:32:52.7: Send Observation V5584_Sgr to ACP Sequencer 16-May-2014 09:03:45.1: Acquisition time: 1852.3638535 sec. 16-May-2014 09:03:45.1: Data for Observation V5584_Sgr acquired successfully. 16-May-2014 09:03:45.1: Image Efficiency: 80.9% 16-May-2014 09:03:45.1: Cycle Efficiency: 99.9% 16-May-2014 09:03:45.7: ++ Auto Focus ++ 16-May-2014 09:03:45.7: Doing periodic autofocus. 16-May-2014 09:03:45.7: Start special ACP AutoFocus script for scheduler 16-May-2014 09:06:52.4: Next periodic autofocus ASAP after 16-May-2014 11:06:14 UTC 16-May-2014 09:06:54.9: Dispatcher cycle time: 0.561601 sec. 16-May-2014 09:06:54.9: Acquire data for Observation PSN_J2237... 16-May-2014 09:06:54.9: (belongs to Project AAVSO_Monitoring, Plan PSN_J2237) 16-May-2014 09:06:54.9: Doing 3 sets 16-May-2014 09:06:54.9: Send Observation PSN_J2237 to ACP Sequencer 16-May-2014 09:17:51.9: Send Observation PSN_J2237 to ACP Sequencer 16-May-2014 09:27:19.8: Send Observation PSN_J2237 to ACP Sequencer 16-May-2014 09:36:53.5: Acquisition time: 1798.513559 sec. 16-May-2014 09:36:53.5: Data for Observation PSN_J2237 acquired successfully. 16-May-2014 09:36:53.5: Image Efficiency: 70.0% 16-May-2014 09:36:53.5: Cycle Efficiency: 100.0% 16-May-2014 09:36:56.8: Dispatcher cycle time: 0.9516016 sec. 16-May-2014 09:36:56.8: Acquire data for Observation V496_Sct... 16-May-2014 09:36:56.8: (belongs to Project Nova_Monitoring, Plan V496_Sct) 16-May-2014 09:36:56.8: Send Observation V496_Sct to ACP Sequencer 16-May-2014 10:09:05.3: Acquisition time: 1928.4919873 sec. 16-May-2014 10:09:05.3: Data for Observation V496_Sct acquired successfully. 16-May-2014 10:09:05.3: Image Efficiency: 77.7% 16-May-2014 10:09:05.3: Cycle Efficiency: 99.9% 16-May-2014 10:09:12.2: Dispatcher cycle time: 0.9204016 sec. 16-May-2014 10:09:12.2: Acquire data for Observation V1723_Aql... 16-May-2014 10:09:12.2: (belongs to Project Nova_Monitoring, Plan V1723_Aql) 16-May-2014 10:09:12.2: Send Observation V1723_Aql to ACP Sequencer 16-May-2014 10:28:07.9: Acquisition time: 1135.7453949 sec. 16-May-2014 10:28:07.9: Data for Observation V1723_Aql acquired successfully. 16-May-2014 10:28:08.0: Image Efficiency: 79.2% 16-May-2014 10:28:08.0: Cycle Efficiency: 99.9% 16-May-2014 10:28:13.2: Dispatcher cycle time: 0.9204016 sec. 16-May-2014 10:28:13.2: Acquire data for Observation V1724_Aql... 16-May-2014 10:28:13.2: (belongs to Project Nova_Monitoring, Plan V1724_Aql) 16-May-2014 10:28:13.2: Send Observation V1724_Aql to ACP Sequencer 16-May-2014 10:46:41.3: Acquisition time: 1108.1489464 sec. 16-May-2014 10:46:41.3: Data for Observation V1724_Aql acquired successfully. 16-May-2014 10:46:41.4: Image Efficiency: 81.1% 16-May-2014 10:46:41.4: Cycle Efficiency: 99.9% 16-May-2014 10:46:52.1: Dispatcher cycle time: 0.3588006 sec. 16-May-2014 10:46:52.1: Acquire data for Observation ST_Lyr... 16-May-2014 10:46:52.1: (belongs to Project Mogul_Mira, Plan ST_Lyr) 16-May-2014 10:46:52.1: Send Observation ST_Lyr to ACP Sequencer 16-May-2014 11:04:47.4: Acquisition time: 1075.2952887 sec. 16-May-2014 11:04:47.4: Data for Observation ST_Lyr acquired successfully. 16-May-2014 11:04:47.5: Image Efficiency: 48.8% 16-May-2014 11:04:47.5: Cycle Efficiency: 100.0% 16-May-2014 11:04:56.8: Dispatcher cycle time: 0.0780001 sec. 16-May-2014 11:04:56.8: Acquire data for Observation GSC_3649-0825... 16-May-2014 11:04:56.8: (belongs to Project Staels, Plan GSC_3649-0825) 16-May-2014 11:04:56.8: Send Observation GSC_3649-0825 to ACP Sequencer 16-May-2014 11:08:19.6: ACP data acquisition failed for Observation GSC_3649-0825. 16-May-2014 11:08:19.6: (Observation GSC_3649-0825 interrupted by acquisition process failure.) 16-May-2014 11:08:26.1: ++ Auto Focus ++ 16-May-2014 11:08:26.1: Doing periodic autofocus. 16-May-2014 11:08:26.1: Start special ACP AutoFocus script for scheduler 16-May-2014 11:12:34.1: Next periodic autofocus ASAP after 16-May-2014 13:11:32 UTC 16-May-2014 11:13:07.1: ++ Dawn Flats ++ 16-May-2014 11:13:07.2: Start ACP AutoFlat script for Dawn 16-May-2014 12:03:42.6: -- Observatory Shutdown -- 16-May-2014 12:03:42.6: Weather disconnected. 16-May-2014 12:03:42.6: Start ACP Sequencer's ShutdownObs script 16-May-2014 12:07:18.6: Weather successfully connected. Wait for valid data... 16-May-2014 16:56:19.8: Dispatcher stopped at 16-May-2014 16:56:19 UTC 16-May-2014 16:56:19.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 16-May-2014 23:05:08.1: -- Weather Unsafe -- 16-May-2014 23:25:15.4: ++ Weather Safe ++ 17-May-2014 03:16:47.6: -- Weather Unsafe -- 17-May-2014 04:29:02.9: ++ Weather Safe ++ 17-May-2014 17:22:27.9: -- Weather Unsafe -- 17-May-2014 17:42:25.3: ++ Weather Safe ++ 18-May-2014 03:30:24.9: Dispatcher started at 18-May-2014 03:30:24 UTC 18-May-2014 03:30:24.9: Priority: W[0]=1.00 18-May-2014 03:30:24.9: Transit Altitude: W[1]=0.70 18-May-2014 03:30:24.9: Highest Altitude: W[6]=0.00 18-May-2014 03:30:24.9: Lateness: W[5]=0.00 18-May-2014 03:30:24.9: Slew Distance: W[2]=0.30 18-May-2014 03:30:24.9: Retry Count: W[3]=0.20 18-May-2014 03:30:24.9: Meridian Crossing: W[4]=0.30 18-May-2014 03:30:24.9: Obs Conditions: W[7]=0.40 18-May-2014 03:30:24.9: Rising Plan Delay: disabled 18-May-2014 03:30:26.4: Run statistics: 18-May-2014 03:30:26.4: Observations: 130 18-May-2014 03:30:26.4: Considered: 75 18-May-2014 03:30:26.4: Completed: 14 18-May-2014 03:30:26.4: Skipped: 55 18-May-2014 03:30:26.4: Never Eligible: 55 18-May-2014 03:30:26.4: Failed: 6 Log closed at Sun, May 18 2014 03:30:26 UTC (actual time)