Beta 387: Sequence fails to transition to new target

lucam · November 30, 2019, 1:55pm

Hi guys,

I had two targets loaded last night. The first one had an end time of 4:10am and the second 6:20am. SGP imaged until the first time limit was reached then it failed to start the second target. I have copied the relevant section of log file below. I have also attached a screenshot of the setup of the second target.

Does anything jump at you as being wrong? In the log file, it looks like target Sh2-274 is primed to start but then nothing happens.

thanks,

Luca

[11/30/19 04:08:51.134][DEBUG] [Sequence Thread] Target past specified end time, moving to next target…
[11/30/19 04:08:51.134][DEBUG] [Sequence Thread] Run event requested sequence break due to time limit, moving to next target…
[11/30/19 04:08:51.134][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model…
[11/30/19 04:08:51.164][DEBUG] [Main Thread] Adding sequence level notification: Target terminated early due to target end time constraint…
[11/30/19 04:08:51.169][DEBUG] [Sequence Thread] Sending Notification: Status - Target terminated early due to target end time constraint…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Attempting to move to next event…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Finding first active group…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target IC 1805…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 11/30/2019 4:10:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target Sh2-274…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 11/29/2019 6:20:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target M1…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 6:00:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target Flat…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 12/8/2018 4:59:00 PM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target NGC 7335…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 4:30:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target NGC 5985…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 4:15:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target NGC 2359…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 3:30:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target IC 405…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 3:00:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target B 33…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/21/2019 10:00:00 PM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Looking at target IC 410…
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is active: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target is complete: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target marked as past end time: False
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target has end time: True
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Target end time: 10/22/2019 6:00:00 AM
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] Sequence complete.
[11/30/19 04:08:51.277][DEBUG] [Sequence Thread] No valid targets remain, aborting…
[11/30/19 04:08:51.278][DEBUG] [Sequence Thread] ********* Run post sequence *********
[11/30/19 04:08:51.278][DEBUG] [Sequence Thread] SGPro capture cal frame mode is OFF…
[11/30/19 04:08:51.278][DEBUG] [Sequence Thread] Capture calibration frames on sequence failure is active. Abort status: False; User Abort: True
[11/30/19 04:08:51.278][DEBUG] [Sequence Thread] Turning capture cal frame mode OFF (because sequence was aborted by user) or no calibration frames exist.
[11/30/19 04:08:51.280][DEBUG] [Sequence Thread] Clearing timed monitoring events…
[11/30/19 04:08:51.280][DEBUG] [Sequence Thread] Checking RunEndOfSequenceEquipmentOptions, force = True
[11/30/19 04:08:51.280][DEBUG] [Sequence Thread] In RunEndOfSequenceEquipmentOptions
[11/30/19 04:08:52.267][DEBUG] [Image History Worker] Star detection using min star size of 2px…
[11/30/19 04:08:52.267][DEBUG] [Image History Worker] Star detection using max star size of 40px…
[11/30/19 04:08:52.311][DEBUG] [Image History Worker] Find stars took: 1801 ms…
[11/30/19 04:08:52.311][DEBUG] [Image History Worker] Star list contains 197 stars…
[11/30/19 04:08:52.311][DEBUG] [Image History Worker] Calculating HFR with sample size: 4
[11/30/19 04:08:52.391][DEBUG] [Image History Worker] Image analysis (async) END
[11/30/19 04:09:22.259][DEBUG] [Main Thread] Adding sequence level notification: Warming the CCD…
[11/30/19 04:09:22.265][DEBUG] [Sequence Thread] Sending Notification: Status - Warming the CCD…
[11/30/19 04:09:22.265][DEBUG] [Main Thread] Adding sequence level notification: Auto guider has been stopped successfully.
[11/30/19 04:09:22.268][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received…
[11/30/19 04:09:22.268][DEBUG] [TEC Thread] TEC Change: Starting…

lucam · November 30, 2019, 1:57pm

I see the problem: somehow when I changed the time, the date did not switch to the following morning but it kept the date before midnight. I don’t know if I messed up or if it is a bug. I don’t think I can change the date, only the time.

thanks!

Luca

Ken · November 30, 2019, 6:44pm

I think this is a bug. At what point did you change the end time and for which targets? During a sequence? Before you ran it?

The full log would be helpful here.

lucam · November 30, 2019, 7:48pm

The full log is here:

I am pretty sure I added the target after I had already launched the sequence.

Thanks,

Luca

Ken · November 30, 2019, 8:09pm

Ok, great. That is helpful. I have made changes that will always update a target’s end time before it is considered for selection. If SGPro cannot calculate the target’s end time, it will be skipped (and a notification sent).

This will be in releases after 3.1.0.390

Ross_Walker · November 30, 2019, 11:13pm

Ken, you’ve probably already nailed this bug, but just in case, I can report what I believe to be the same problem with 3.1.0.387:

I also added a new target (Abell 6) after the sequence was running. I had set the end time of Abell 6 to 5:20am and I believe SGP correctly reported the time as “Tomorrow” and not “Today”. For some reason the sequence ended at 1:36am when it moved to Abell 6 but decided the sequence was complete. Remaining time for that target was 4 hours (2 events). The target settings look good so I’m not sure why the sequence ended there. I did forget to set “slew and center” (it was set to “do not move to location”) but I assume that would not end the sequence but instead give my 4 hours of off-target images.

Update: Just viewed the sequence file and indeed, the end time for that target has not incremented to “tomorrow” (December 1st); it’s still set to 2019/11/30 5am so I guess that is why the sequence ended.

[12/01/19 01:36:12.546][DEBUG] [Sequence Thread] Sending Notification: Status - Target terminated early due to target end time constraint…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Attempting to move to next event…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Finding first active group…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Looking at target NGC 521…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target is active: True
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target is complete: False
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target marked as past end time: True
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target has end time: True
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target end time: 01/12/2019 01:40:00
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Looking at target Abell 6…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target is active: True
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target is complete: False
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target marked as past end time: False
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target has end time: True
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Target end time: 30/11/2019 05:20:00
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] Sequence complete.
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] No valid targets remain, aborting…
[12/01/19 01:36:12.586][DEBUG] [Sequence Thread] ********* Run post sequence *********

PS: is there a setting where I can default the “slew and center” setting to on for all new sequences? I have a feeling the current “do not move to location” is going to catch me out more than a few time.

Cheers and thanks for your valiant efforts!

Ross