Log opened at Thu, Feb 01 2018 02:39:03 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 01-Feb-2018 02:39:03.6: Simulated clock initialized at 01-Feb-2018 02:39:03 UTC 01-Feb-2018 02:39:03.7: Loaded Constraint plugin AirMass 01-Feb-2018 02:39:03.7: Loaded Constraint plugin AirmassRange 01-Feb-2018 02:39:03.7: Loaded Constraint plugin Horizon 01-Feb-2018 02:39:03.7: Loaded Constraint plugin HourAngle 01-Feb-2018 02:39:03.7: Loaded Constraint plugin MoonAvoid 01-Feb-2018 02:39:03.7: Loaded Constraint plugin MoonDown 01-Feb-2018 02:39:03.7: Loaded Constraint plugin SkyCondition 01-Feb-2018 02:39:03.7: Loaded Constraint plugin TimeRange 01-Feb-2018 02:39:04.2: 26 plans are now pending. 01-Feb-2018 02:39:04.2: Attach ACP sequencer 01-Feb-2018 02:39:15.5: Dispatcher started at 01-Feb-2018 02:39:15 UTC 01-Feb-2018 02:39:15.5: Priority: W[0]=0.00 01-Feb-2018 02:39:15.5: Transit Altitude: W[1]=0.70 01-Feb-2018 02:39:15.5: Highest Altitude: W[6]=0.00 01-Feb-2018 02:39:15.5: Lateness: W[5]=0.00 01-Feb-2018 02:39:15.5: Slew Distance: W[2]=0.00 01-Feb-2018 02:39:15.5: Retry Count: W[3]=0.20 01-Feb-2018 02:39:15.5: Meridian Crossing: W[4]=0.00 01-Feb-2018 02:39:15.5: Obs Conditions: W[7]=0.40 01-Feb-2018 02:39:15.5: Rising Plan Delay: disabled 01-Feb-2018 02:39:25.4: Weather successfully connected. Wait for valid data... 01-Feb-2018 02:39:25.4: -- Weather Unsafe -- 01-Feb-2018 03:03:06.7: ++ Weather Safe ++ 01-Feb-2018 03:03:08.2: ++ Observatory Startup ++ 01-Feb-2018 03:03:08.2: Start ACP Sequencer's StartupObs script 01-Feb-2018 03:03:18.2: Sequencer is now active 01-Feb-2018 03:03:23.3: Sequencer is no longer active 01-Feb-2018 03:03:23.3: Open the observatory dome/roof 01-Feb-2018 03:04:29.5: ++ Auto Focus ++ 01-Feb-2018 03:04:29.6: Doing initial autofocus. 01-Feb-2018 03:04:29.6: Start special ACP AutoFocus script for scheduler 01-Feb-2018 03:04:39.6: Sequencer is now active 01-Feb-2018 03:07:06.7: ** Script interrupted by weather safety event. 01-Feb-2018 03:07:06.7: ** Autofocus failed (n=1), will retry ASAP after 0 min. 01-Feb-2018 03:07:07.3: -- Weather Unsafe -- 01-Feb-2018 04:53:54.6: ++ Weather Safe ++ 01-Feb-2018 04:53:56.0: ++ Observatory Startup ++ 01-Feb-2018 04:53:56.0: Start ACP Sequencer's StartupObs script 01-Feb-2018 04:54:06.4: Sequencer is now active 01-Feb-2018 04:54:11.6: Sequencer is no longer active 01-Feb-2018 04:54:11.6: Open the observatory dome/roof 01-Feb-2018 04:55:32.6: ++ Auto Focus ++ 01-Feb-2018 04:55:32.7: Doing initial autofocus. 01-Feb-2018 04:55:32.7: Start special ACP AutoFocus script for scheduler 01-Feb-2018 04:55:43.1: Sequencer is now active 01-Feb-2018 04:58:37.5: Sequencer is no longer active 01-Feb-2018 04:58:37.5: Next periodic autofocus ASAP after 01-Feb-2018 07:58:21 UTC 01-Feb-2018 04:58:37.9: Plan V0959_Mon has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:37.9: Plan MP_Gem has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:37.9: Plan CB_32 has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan HQ_Aur has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan HX_Aur has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan FW_Cam has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan HI_Cam has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan HO_Cam has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan DN_Lyn has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan T_Ori has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan V841_Oph has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan KK_UMa has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: Plan RX_J0524+42 has Monitor Mode. Time to resubmit it. 01-Feb-2018 04:58:38.0: SelectRunning: No running plans 01-Feb-2018 04:58:38.0: Obs 2MASS_J19395313+0557550 in Plan 2MASS_J19395313+0557550 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.0: AirmassRange estimates ready after 01-Feb-2018 14:07:07 UTC 01-Feb-2018 04:58:38.0: Obs AF_And in Plan AF_And was vetoed by AirMass constraint. 01-Feb-2018 04:58:38.0: AirMass estimates ready after 01-Feb-2018 17:56:16 UTC 01-Feb-2018 04:58:38.0: Obs BC_Lac in Plan BC_Lac was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.0: AirmassRange estimates ready after 01-Feb-2018 15:43:21 UTC 01-Feb-2018 04:58:38.1: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 01-Feb-2018 04:58:38.1: AirMass estimates ready after 01-Feb-2018 16:20:33 UTC 01-Feb-2018 04:58:38.1: Obs IRAS_19067+2158 in Plan IRAS_19067+2158 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.1: AirmassRange estimates ready after 01-Feb-2018 12:58:31 UTC 01-Feb-2018 04:58:38.1: Obs IRAS_19183+2339 in Plan IRAS_19183+2339 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.1: AirmassRange estimates ready after 01-Feb-2018 13:06:35 UTC 01-Feb-2018 04:58:38.1: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.1: AirmassRange estimates ready after 01-Feb-2018 12:50:12 UTC 01-Feb-2018 04:58:38.1: Obs Mkn_501 in Plan Mkn_501 was vetoed by AirMass constraint. 01-Feb-2018 04:58:38.1: AirMass estimates ready after 01-Feb-2018 10:10:26 UTC 01-Feb-2018 04:58:38.1: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.1: AirmassRange estimates ready after 01-Feb-2018 16:35:09 UTC 01-Feb-2018 04:58:38.2: Obs NSVS_11201899 in Plan NSVS_11201899 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 12:59:32 UTC 01-Feb-2018 04:58:38.2: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint. 01-Feb-2018 04:58:38.2: AirMass estimates ready after 01-Feb-2018 05:08:59 UTC 01-Feb-2018 04:58:38.2: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 17:56:53 UTC 01-Feb-2018 04:58:38.2: STRICT VETO: 01-Feb-2018 04:58:38.2: Obs V0524_Cas in Plan V0524_Cas was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 17:04:57 UTC 01-Feb-2018 04:58:38.2: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 15:45:48 UTC 01-Feb-2018 04:58:38.2: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 13:30:44 UTC 01-Feb-2018 04:58:38.2: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 13:18:26 UTC 01-Feb-2018 04:58:38.2: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 13:44:47 UTC 01-Feb-2018 04:58:38.2: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 13:38:14 UTC 01-Feb-2018 04:58:38.2: Obs V1904_Cyg in Plan V1904_Cyg was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 14:50:55 UTC 01-Feb-2018 04:58:38.2: Obs V1907_Cyg in Plan V1907_Cyg was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.2: AirmassRange estimates ready after 01-Feb-2018 14:47:36 UTC 01-Feb-2018 04:58:38.3: Obs V2481_Cyg in Plan V2481_Cyg was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.3: AirmassRange estimates ready after 01-Feb-2018 14:23:33 UTC 01-Feb-2018 04:58:38.3: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.3: AirmassRange estimates ready after 01-Feb-2018 14:15:37 UTC 01-Feb-2018 04:58:38.3: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 01-Feb-2018 04:58:38.3: AirmassRange estimates ready after 01-Feb-2018 12:34:03 UTC 01-Feb-2018 04:58:38.3: Efficiency: Selecting from 16 pending plans: 01-Feb-2018 04:58:38.4: Selected Plan V0959_Mon (TotEff=0.90) 01-Feb-2018 04:58:38.6: Dispatcher cycle time: 1.0270588 sec. 01-Feb-2018 04:58:38.6: Acquire data for Observation V0959_Mon... 01-Feb-2018 04:58:38.6: (belongs to Project Nova_Monitoring, Plan V0959_Mon) 01-Feb-2018 04:58:38.6: Send Observation V0959_Mon to ACP Sequencer 01-Feb-2018 04:58:48.6: Sequencer is now active 01-Feb-2018 05:13:46.8: Sequencer is no longer active 01-Feb-2018 05:13:47.1: Post-job status check done (stat=Completed) 01-Feb-2018 05:13:47.1: Acquisition time: 908.5799678 sec. 01-Feb-2018 05:13:47.1: Data for Observation V0959_Mon acquired successfully. 01-Feb-2018 05:13:47.2: Image Efficiency: 69.3% 01-Feb-2018 05:13:47.2: Cycle Efficiency: 99.9% 01-Feb-2018 05:13:47.5: SelectRunning: No running plans 01-Feb-2018 05:13:47.5: Obs GSC_4030_204 in Plan GSC_4030_204 was vetoed by AirMass constraint. 01-Feb-2018 05:13:47.5: AirMass estimates ready after 01-Feb-2018 17:44:28 UTC 01-Feb-2018 05:13:47.6: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by MoonDown constraint. 01-Feb-2018 05:13:47.6: MoonDown estimates ready after 01-Feb-2018 15:04:56 UTC 01-Feb-2018 05:13:47.6: Efficiency: Selecting from 14 pending plans: 01-Feb-2018 05:13:47.7: Selected Plan MP_Gem (TotEff=0.90) 01-Feb-2018 05:13:47.8: Dispatcher cycle time: 0.52503 sec. 01-Feb-2018 05:13:47.8: Acquire data for Observation MP_Gem... 01-Feb-2018 05:13:47.8: (belongs to Project Staels, Plan MP_Gem) 01-Feb-2018 05:13:47.9: Send Observation MP_Gem to ACP Sequencer 01-Feb-2018 05:13:57.9: Sequencer is now active 01-Feb-2018 05:44:41.0: Sequencer is no longer active 01-Feb-2018 05:44:41.0: Post-job status check done (stat=Completed) 01-Feb-2018 05:44:41.0: Acquisition time: 1853.1079917 sec. 01-Feb-2018 05:44:41.0: Data for Observation MP_Gem acquired successfully. 01-Feb-2018 05:44:41.0: Image Efficiency: 77.7% 01-Feb-2018 05:44:41.0: Cycle Efficiency: 100.0% 01-Feb-2018 05:44:41.1: SelectRunning: No running plans 01-Feb-2018 05:44:41.2: Efficiency: Selecting from 13 pending plans: 01-Feb-2018 05:44:41.3: Selected Plan DN_Lyn (TotEff=0.90) 01-Feb-2018 05:44:41.5: Dispatcher cycle time: 0.3640208 sec. 01-Feb-2018 05:44:41.5: Acquire data for Observation DN_Lyn... 01-Feb-2018 05:44:41.5: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan DN_Lyn) 01-Feb-2018 05:44:41.5: Send Observation DN_Lyn to ACP Sequencer 01-Feb-2018 05:44:51.5: Sequencer is now active 01-Feb-2018 05:58:32.3: Sequencer is no longer active 01-Feb-2018 05:58:32.3: Post-job status check done (stat=Completed) 01-Feb-2018 05:58:32.3: Acquisition time: 830.8125197 sec. 01-Feb-2018 05:58:32.3: Data for Observation DN_Lyn acquired successfully. 01-Feb-2018 05:58:32.3: Image Efficiency: 46.9% 01-Feb-2018 05:58:32.3: Cycle Efficiency: 100.0% 01-Feb-2018 05:58:32.5: SelectRunning: No running plans 01-Feb-2018 05:58:32.6: Efficiency: Selecting from 12 pending plans: 01-Feb-2018 05:58:32.7: Selected Plan HO_Cam (TotEff=0.80) 01-Feb-2018 05:58:32.9: Dispatcher cycle time: 0.3490199 sec. 01-Feb-2018 05:58:32.9: Acquire data for Observation HO_Cam... 01-Feb-2018 05:58:32.9: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam) 01-Feb-2018 05:58:32.9: Send Observation HO_Cam to ACP Sequencer 01-Feb-2018 05:58:42.9: Sequencer is now active 01-Feb-2018 06:12:47.2: Sequencer is no longer active 01-Feb-2018 06:12:47.2: Post-job status check done (stat=Completed) 01-Feb-2018 06:12:47.2: Acquisition time: 854.3538663 sec. 01-Feb-2018 06:12:47.2: Data for Observation HO_Cam acquired successfully. 01-Feb-2018 06:12:47.3: Image Efficiency: 45.6% 01-Feb-2018 06:12:47.3: Cycle Efficiency: 100.0% 01-Feb-2018 06:12:47.6: SelectRunning: No running plans 01-Feb-2018 06:12:47.7: Efficiency: Selecting from 11 pending plans: 01-Feb-2018 06:12:47.8: Selected Plan HQ_Aur (TotEff=0.77) 01-Feb-2018 06:12:47.9: Dispatcher cycle time: 0.3430196 sec. 01-Feb-2018 06:12:47.9: Acquire data for Observation HQ_Aur... 01-Feb-2018 06:12:47.9: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HQ_Aur) 01-Feb-2018 06:12:48.0: Send Observation HQ_Aur to ACP Sequencer 01-Feb-2018 06:12:58.0: Sequencer is now active 01-Feb-2018 06:26:25.2: Sequencer is no longer active 01-Feb-2018 06:26:25.2: Post-job status check done (stat=Completed) 01-Feb-2018 06:26:25.2: Acquisition time: 817.284746 sec. 01-Feb-2018 06:26:25.2: Data for Observation HQ_Aur acquired successfully. 01-Feb-2018 06:26:25.3: Image Efficiency: 47.7% 01-Feb-2018 06:26:25.3: Cycle Efficiency: 100.0% 01-Feb-2018 06:26:25.4: SelectRunning: No running plans 01-Feb-2018 06:26:25.5: STRICT VETO: 01-Feb-2018 06:26:25.5: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 01-Feb-2018 06:26:25.5: AirmassRange estimates ready after 01-Feb-2018 20:30:55 UTC 01-Feb-2018 06:26:25.5: Efficiency: Selecting from 9 pending plans: 01-Feb-2018 06:26:25.6: Selected Plan HI_Cam (TotEff=0.74) 01-Feb-2018 06:26:25.8: Dispatcher cycle time: 0.333019 sec. 01-Feb-2018 06:26:25.8: Acquire data for Observation HI_Cam... 01-Feb-2018 06:26:25.8: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HI_Cam) 01-Feb-2018 06:26:25.8: Send Observation HI_Cam to ACP Sequencer 01-Feb-2018 06:26:35.8: Sequencer is now active 01-Feb-2018 06:40:24.0: Sequencer is no longer active 01-Feb-2018 06:40:24.0: Post-job status check done (stat=Completed) 01-Feb-2018 06:40:24.0: Acquisition time: 838.2339442 sec. 01-Feb-2018 06:40:24.0: Data for Observation HI_Cam acquired successfully. 01-Feb-2018 06:40:24.0: Image Efficiency: 46.5% 01-Feb-2018 06:40:24.0: Cycle Efficiency: 100.0% 01-Feb-2018 06:40:24.4: SelectRunning: No running plans 01-Feb-2018 06:40:24.5: Efficiency: Selecting from 8 pending plans: 01-Feb-2018 06:40:24.5: Selected Plan KK_UMa (TotEff=0.75) 01-Feb-2018 06:40:24.7: Dispatcher cycle time: 0.3480199 sec. 01-Feb-2018 06:40:24.7: Acquire data for Observation KK_UMa... 01-Feb-2018 06:40:24.7: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan KK_UMa) 01-Feb-2018 06:40:24.7: Send Observation KK_UMa to ACP Sequencer 01-Feb-2018 06:40:34.7: Sequencer is now active 01-Feb-2018 06:55:46.9: Sequencer is no longer active 01-Feb-2018 06:55:46.9: Post-job status check done (stat=Completed) 01-Feb-2018 06:55:46.9: Acquisition time: 922.1597445 sec. 01-Feb-2018 06:55:46.9: Data for Observation KK_UMa acquired successfully. 01-Feb-2018 06:55:46.9: Image Efficiency: 42.3% 01-Feb-2018 06:55:46.9: Cycle Efficiency: 100.0% 01-Feb-2018 06:55:47.0: SelectRunning: No running plans 01-Feb-2018 06:55:47.1: Efficiency: Selecting from 7 pending plans: 01-Feb-2018 06:55:47.2: Selected Plan M67 (TotEff=0.70) 01-Feb-2018 06:55:47.3: Dispatcher cycle time: 0.3050175 sec. 01-Feb-2018 06:55:47.3: Acquire data for Observation M67... 01-Feb-2018 06:55:47.3: (belongs to Project AAVSO_Monitoring, Plan M67) 01-Feb-2018 06:55:47.3: Send Observation M67 to ACP Sequencer 01-Feb-2018 06:55:57.4: Sequencer is now active 01-Feb-2018 07:27:49.4: Sequencer is no longer active 01-Feb-2018 07:27:49.4: Post-job status check done (stat=Completed) 01-Feb-2018 07:27:49.4: Acquisition time: 1922.0559353 sec. 01-Feb-2018 07:27:49.4: Data for Observation M67 acquired successfully. 01-Feb-2018 07:27:49.4: Image Efficiency: 73.3% 01-Feb-2018 07:27:49.4: Cycle Efficiency: 100.0% 01-Feb-2018 07:27:49.6: SelectRunning: No running plans 01-Feb-2018 07:27:49.7: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 01-Feb-2018 07:27:49.7: AirmassRange estimates ready after 02-Feb-2018 00:37:14 UTC 01-Feb-2018 07:27:49.7: Efficiency: Selecting from 5 pending plans: 01-Feb-2018 07:27:49.7: Selected Plan FW_Cam (TotEff=0.61) 01-Feb-2018 07:27:49.9: Dispatcher cycle time: 0.2750157 sec. 01-Feb-2018 07:27:49.9: Acquire data for Observation FW_Cam... 01-Feb-2018 07:27:49.9: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam) 01-Feb-2018 07:27:49.9: Send Observation FW_Cam to ACP Sequencer 01-Feb-2018 07:27:59.9: Sequencer is now active 01-Feb-2018 07:43:24.2: Sequencer is no longer active 01-Feb-2018 07:43:24.2: Post-job status check done (stat=Completed) 01-Feb-2018 07:43:24.2: Acquisition time: 934.3564422 sec. 01-Feb-2018 07:43:24.2: Data for Observation FW_Cam acquired successfully. 01-Feb-2018 07:43:24.3: Image Efficiency: 41.7% 01-Feb-2018 07:43:24.3: Cycle Efficiency: 100.0% 01-Feb-2018 07:43:24.5: SelectRunning: No running plans 01-Feb-2018 07:43:24.5: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 01-Feb-2018 07:43:24.5: AirMass estimates ready after 02-Feb-2018 00:20:25 UTC 01-Feb-2018 07:43:24.5: Efficiency: Selecting from 3 pending plans: 01-Feb-2018 07:43:24.5: Selected Plan HX_Aur (TotEff=0.58) 01-Feb-2018 07:43:24.7: Dispatcher cycle time: 0.2050117 sec. 01-Feb-2018 07:43:24.7: Acquire data for Observation HX_Aur... 01-Feb-2018 07:43:24.7: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HX_Aur) 01-Feb-2018 07:43:24.7: Send Observation HX_Aur to ACP Sequencer 01-Feb-2018 07:43:34.7: Sequencer is now active 01-Feb-2018 07:57:14.6: Sequencer is no longer active 01-Feb-2018 07:57:14.6: Post-job status check done (stat=Completed) 01-Feb-2018 07:57:14.6: Acquisition time: 829.8974674 sec. 01-Feb-2018 07:57:14.6: Data for Observation HX_Aur acquired successfully. 01-Feb-2018 07:57:14.6: Image Efficiency: 47.0% 01-Feb-2018 07:57:14.6: Cycle Efficiency: 100.0% 01-Feb-2018 07:57:14.8: SelectRunning: No running plans 01-Feb-2018 07:57:14.9: Efficiency: Selecting from 2 pending plans: 01-Feb-2018 07:57:14.9: Selected Plan SDSS073224 (TotEff=0.53) 01-Feb-2018 07:57:15.0: Dispatcher cycle time: 0.1840105 sec. 01-Feb-2018 07:57:15.0: Acquire data for Observation SDSS073224... 01-Feb-2018 07:57:15.0: (belongs to Project AGN, Plan SDSS073224) 01-Feb-2018 07:57:15.0: Send Observation SDSS073224 to ACP Sequencer 01-Feb-2018 07:57:25.0: Sequencer is now active 01-Feb-2018 08:53:02.6: Sequencer is no longer active 01-Feb-2018 08:53:02.6: Post-job status check done (stat=Completed) 01-Feb-2018 08:53:02.6: Acquisition time: 3347.6104724 sec. 01-Feb-2018 08:53:02.6: Data for Observation SDSS073224 acquired successfully. 01-Feb-2018 08:53:02.7: Image Efficiency: 71.7% 01-Feb-2018 08:53:02.7: Cycle Efficiency: 100.0% 01-Feb-2018 08:53:02.8: ++ Auto Focus ++ 01-Feb-2018 08:53:02.9: Doing periodic autofocus. 01-Feb-2018 08:53:02.9: Start special ACP AutoFocus script for scheduler 01-Feb-2018 08:53:12.9: Sequencer is now active 01-Feb-2018 08:56:45.0: Sequencer is no longer active 01-Feb-2018 08:56:45.0: Next periodic autofocus ASAP after 01-Feb-2018 11:55:59 UTC 01-Feb-2018 08:56:45.1: SelectRunning: No running plans 01-Feb-2018 08:56:45.1: Efficiency: Only one pending plan RX_J0524+42, it is the "best" 01-Feb-2018 08:56:45.3: Dispatcher cycle time: 0.17601 sec. 01-Feb-2018 08:56:45.3: Acquire data for Observation RX_J0524+42... 01-Feb-2018 08:56:45.3: (belongs to Project Walker, Plan RX_J0524+42) 01-Feb-2018 08:56:45.3: Doing 30 sets 01-Feb-2018 08:56:45.3: Send Observation RX_J0524+42 to ACP Sequencer 01-Feb-2018 08:56:55.3: Sequencer is now active 01-Feb-2018 09:24:11.1: Sequencer is no longer active 01-Feb-2018 09:24:11.1: Post-job status check done (stat=Completed) 01-Feb-2018 09:24:11.2: TestConstraints: AirMass vetoed/failed for end time of Obs 01-Feb-2018 09:24:11.2: ***Running Plan RX_J0524+42 (best efforts) completed early: 01-Feb-2018 09:24:11.2: Obs RX_J0524+42 would would not meet constraints 01-Feb-2018 09:24:11.2: Acquisition time: 1645.8701384 sec. 01-Feb-2018 09:24:11.2: Data for Observation RX_J0524+42 acquired successfully. 01-Feb-2018 09:24:11.2: Image Efficiency: 72.9% 01-Feb-2018 09:24:11.2: Cycle Efficiency: 100.0% 01-Feb-2018 09:24:11.3: SelectRunning: No running plans 01-Feb-2018 09:24:11.3: No pending plans that can be started now. 01-Feb-2018 09:24:11.3: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:24:41.6: SelectRunning: No running plans 01-Feb-2018 09:24:41.6: No pending plans that can be started now. 01-Feb-2018 09:24:41.6: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:25:12.2: SelectRunning: No running plans 01-Feb-2018 09:25:12.2: No pending plans that can be started now. 01-Feb-2018 09:25:12.2: Dispatcher cycle time: 0.0130008 sec. 01-Feb-2018 09:25:43.0: SelectRunning: No running plans 01-Feb-2018 09:25:43.0: No pending plans that can be started now. 01-Feb-2018 09:25:43.0: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:26:13.1: SelectRunning: No running plans 01-Feb-2018 09:26:13.2: No pending plans that can be started now. 01-Feb-2018 09:26:13.2: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:26:43.7: SelectRunning: No running plans 01-Feb-2018 09:26:43.7: No pending plans that can be started now. 01-Feb-2018 09:26:43.7: Dispatcher cycle time: 0.0300017 sec. 01-Feb-2018 09:27:13.8: SelectRunning: No running plans 01-Feb-2018 09:27:13.8: No pending plans that can be started now. 01-Feb-2018 09:27:13.8: Dispatcher cycle time: 0.0160009 sec. 01-Feb-2018 09:27:43.9: SelectRunning: No running plans 01-Feb-2018 09:27:43.9: No pending plans that can be started now. 01-Feb-2018 09:27:43.9: Dispatcher cycle time: 0.0130008 sec. 01-Feb-2018 09:28:14.1: SelectRunning: No running plans 01-Feb-2018 09:28:14.1: No pending plans that can be started now. 01-Feb-2018 09:28:14.1: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:28:44.5: SelectRunning: No running plans 01-Feb-2018 09:28:44.5: No pending plans that can be started now. 01-Feb-2018 09:28:44.5: Dispatcher cycle time: 0.0130008 sec. 01-Feb-2018 09:29:14.7: SelectRunning: No running plans 01-Feb-2018 09:29:14.7: No pending plans that can be started now. 01-Feb-2018 09:29:14.7: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:29:44.8: SelectRunning: No running plans 01-Feb-2018 09:29:44.8: No pending plans that can be started now. 01-Feb-2018 09:29:44.8: Dispatcher cycle time: 0.0190011 sec. 01-Feb-2018 09:30:15.0: SelectRunning: No running plans 01-Feb-2018 09:30:15.0: No pending plans that can be started now. 01-Feb-2018 09:30:15.0: Dispatcher cycle time: 0.0260015 sec. 01-Feb-2018 09:30:45.4: SelectRunning: No running plans 01-Feb-2018 09:30:45.4: No pending plans that can be started now. 01-Feb-2018 09:30:45.4: Dispatcher cycle time: 0.0110007 sec. 01-Feb-2018 09:31:15.9: SelectRunning: No running plans 01-Feb-2018 09:31:15.9: No pending plans that can be started now. 01-Feb-2018 09:31:15.9: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:31:46.2: SelectRunning: No running plans 01-Feb-2018 09:31:46.2: No pending plans that can be started now. 01-Feb-2018 09:31:46.2: Dispatcher cycle time: 0.0110006 sec. 01-Feb-2018 09:32:16.8: SelectRunning: No running plans 01-Feb-2018 09:32:16.8: No pending plans that can be started now. 01-Feb-2018 09:32:16.8: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:32:47.3: SelectRunning: No running plans 01-Feb-2018 09:32:47.3: No pending plans that can be started now. 01-Feb-2018 09:32:47.3: Dispatcher cycle time: 0.018001 sec. 01-Feb-2018 09:33:18.0: SelectRunning: No running plans 01-Feb-2018 09:33:18.0: No pending plans that can be started now. 01-Feb-2018 09:33:18.0: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:33:48.2: SelectRunning: No running plans 01-Feb-2018 09:33:48.2: No pending plans that can be started now. 01-Feb-2018 09:33:48.2: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:34:18.4: SelectRunning: No running plans 01-Feb-2018 09:34:18.4: No pending plans that can be started now. 01-Feb-2018 09:34:18.4: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:34:48.6: SelectRunning: No running plans 01-Feb-2018 09:34:48.6: No pending plans that can be started now. 01-Feb-2018 09:34:48.6: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:35:18.7: SelectRunning: No running plans 01-Feb-2018 09:35:18.7: No pending plans that can be started now. 01-Feb-2018 09:35:18.7: Dispatcher cycle time: 0.0120007 sec. 01-Feb-2018 09:35:49.3: SelectRunning: No running plans 01-Feb-2018 09:35:49.3: No pending plans that can be started now. 01-Feb-2018 09:35:49.3: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:36:20.0: SelectRunning: No running plans 01-Feb-2018 09:36:20.0: No pending plans that can be started now. 01-Feb-2018 09:36:20.0: Dispatcher cycle time: 0.0130008 sec. 01-Feb-2018 09:36:50.4: SelectRunning: No running plans 01-Feb-2018 09:36:50.4: No pending plans that can be started now. 01-Feb-2018 09:36:50.4: Dispatcher cycle time: 0.0130008 sec. 01-Feb-2018 09:37:20.6: SelectRunning: No running plans 01-Feb-2018 09:37:20.6: No pending plans that can be started now. 01-Feb-2018 09:37:20.6: Dispatcher cycle time: 0.0110006 sec. 01-Feb-2018 09:37:51.1: SelectRunning: No running plans 01-Feb-2018 09:37:51.1: No pending plans that can be started now. 01-Feb-2018 09:37:51.1: Dispatcher cycle time: 0.0130007 sec. 01-Feb-2018 09:38:21.6: -- Weather Unsafe -- 01-Feb-2018 12:55:07.4: ++ Weather Safe ++ 01-Feb-2018 12:55:08.6: ++ Observatory Startup ++ 01-Feb-2018 12:55:08.7: Start ACP Sequencer's StartupObs script 01-Feb-2018 12:55:09.0: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 12:56:09.2: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 12:57:09.5: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 12:58:09.8: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 12:59:10.1: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:00:10.4: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:01:10.6: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:02:11.0: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:03:11.2: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:04:11.4: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:05:11.6: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:06:11.9: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:07:12.0: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:08:12.2: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:09:12.6: WARNING: Request sent while Weather Safety Script is running 01-Feb-2018 13:10:13.3: **EXCEPTION IN SCHEDULER: 01-Feb-2018 13:10:13.3: Observatory startup failed, cannot continue. 01-Feb-2018 13:10:13.3: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 01-Feb-2018 13:10:13.4: Run statistics: 01-Feb-2018 13:10:13.4: Observations: 51 01-Feb-2018 13:10:13.4: Considered: 28 01-Feb-2018 13:10:13.4: Completed: 21 01-Feb-2018 13:10:13.4: Skipped: 4 01-Feb-2018 13:10:13.4: Never Eligible: 23 01-Feb-2018 13:10:13.4: Failed: 3 01-Feb-2018 13:10:13.4: Shutter-Open efficiency: 59.28% 01-Feb-2018 13:10:13.4: Overall Efficiency: 94.89% 01-Feb-2018 13:10:13.4: Release ACP sequencer Log closed at Thu, Feb 01 2018 13:10:15 UTC (actual time)