[10/10/19 03:31:15.946][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[10/10/19 03:31:15.946][DEBUG] [Sequence Thread] EventMarker 173 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:15.988][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.5
[10/10/19 03:31:15.988][DEBUG] [Sequence Thread] PHD2 distance request...
[10/10/19 03:31:15.988][DEBUG] [Sequence Thread] PHD2 distance request returned 0.4...
[10/10/19 03:31:15.999][DEBUG] [Sequence Thread] Distance is below 0.5, starting timer...
[10/10/19 03:31:16.406][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD
[10/10/19 03:31:16.499][DEBUG] [Sequence Thread] PHD2 distance request...
[10/10/19 03:31:16.499][DEBUG] [Sequence Thread] PHD2 distance request returned 0.4...
[10/10/19 03:31:17.006][DEBUG] [Sequence Thread] PHD2 distance request...
[10/10/19 03:31:17.006][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2...
[10/10/19 03:31:17.516][DEBUG] [Sequence Thread] PHD2 distance request...
[10/10/19 03:31:17.516][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2...
[10/10/19 03:31:18.025][DEBUG] [Sequence Thread] PHD2 distance request...
[10/10/19 03:31:18.025][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2...
[10/10/19 03:31:18.157][DEBUG] [Sequence Thread] Distance stayed below 0.5 for 2 seconds, done settling...
[10/10/19 03:31:18.157][DEBUG] [Sequence Thread] Auto guider has settled...
[10/10/19 03:31:18.199][DEBUG] [Sequence Thread] EventMarker 8 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.199][DEBUG] [Sequence Thread] EventMarker 9 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.199][DEBUG] [Sequence Thread] EventMarker 10 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.199][DEBUG] [Sequence Thread] Finished sending frame capture.  Entering wait mode...
[10/10/19 03:31:18.199][DEBUG] [Sequence Thread] EventMarker 11 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.200][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received...
[10/10/19 03:31:18.202][DEBUG] [Sequence Thread] EventMarker 12 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: end exposure called...
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: end readout...
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: CaptureImage...
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: Start exposure...
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: EnableRBIPreflash(False)
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: fetch customer options...
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: exposure time = 90000
[10/10/19 03:31:18.221][DEBUG] [Camera Thread] SBIG Camera: shutter is open...
[10/10/19 03:31:18.250][DEBUG] [Sequence Thread] EventMarker 13 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:31:18.605][DEBUG] [Camera Thread] SBIG Camera: capture command sent...
[10/10/19 03:31:50.510][DEBUG] [Main Thread] PopulateDataModel:  Transferring view to the data model...
[10/10/19 03:31:50.515][DEBUG] [MF Update Thread] Performing serialize...
[10/10/19 03:31:50.522][DEBUG] [MF Update Thread] Serialization took 7 msec.
[10/10/19 03:46:18.583][DEBUG] [Sequence Thread] EventMarker 15 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:18.586][DEBUG] [Sequence Thread] Waking from exposure time sleep period...
[10/10/19 03:46:18.587][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp...
[10/10/19 03:46:18.587][DEBUG] [Sequence Thread] Saving the CCD temp...
[10/10/19 03:46:18.594][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion...
[10/10/19 03:46:18.596][DEBUG] [Sequence Thread] EventMarker 16 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:18.608][DEBUG] [Camera Thread] SBIG Camera status check: INTEGRATING
[10/10/19 03:46:19.609][DEBUG] [Camera Thread] SBIG Camera status check: INTEGRATION_COMPLETE
[10/10/19 03:46:19.631][DEBUG] [Camera Thread] SBIG Camera: end exposure called...
[10/10/19 03:46:19.631][DEBUG] [Camera Thread] SBIG Camera: read data...
[10/10/19 03:46:19.631][DEBUG] [Camera Thread] SBIG Camera: starting readout...
[10/10/19 03:46:19.631][DEBUG] [Camera Thread] SBIG Camera: reading lines...
[10/10/19 03:46:20.125][DEBUG] [Camera Thread] SBIG Camera: end readout...
[10/10/19 03:46:20.125][DEBUG] [Camera Thread] SBIG read complete.  Took 494ms...
[10/10/19 03:46:20.125][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete...
[10/10/19 03:46:20.663][DEBUG] [Sequence Thread] EventMarker 17 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:20.663][DEBUG] [Sequence Thread] Image reported as complete.  Continuing...
[10/10/19 03:46:20.677][DEBUG] [Sequence Thread] Collecting FITs headers...
[10/10/19 03:46:20.677][DEBUG] [Sequence Thread] DATE-LOC time provided by SGPro (failed to retrieve valid entry from camera)...
[10/10/19 03:46:20.738][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info for last solve...
[10/10/19 03:46:20.738][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI...
[10/10/19 03:46:20.765][DEBUG] [Sequence Thread] Clearing timed monitoring events...
[10/10/19 03:46:20.770][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\Stars\Documents\Sequences\Light\NGC7380_Ha_2019-10-10_033103_900sec_1x1_-15C__frame21.fit
[10/10/19 03:46:20.781][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write...
[10/10/19 03:46:20.782][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream...
[10/10/19 03:46:20.782][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers...
[10/10/19 03:46:20.783][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data...
[10/10/19 03:46:20.888][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill...
[10/10/19 03:46:20.888][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap...
[10/10/19 03:46:20.888][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream
[10/10/19 03:46:20.894][DEBUG] [Sequence Thread] Internal Interface: Set Preview...
[10/10/19 03:46:20.894][DEBUG] [Sequence Thread] Display image preview using asynch task...
[10/10/19 03:46:22.418][DEBUG] [Sequence Thread] ---------> Save file took 1653ms
[10/10/19 03:46:22.459][DEBUG] [Sequence Thread] Resuming auto guiding (settling)...
[10/10/19 03:46:22.459][DEBUG] [Sequence Thread] EventMarker 170 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:22.459][DEBUG] [Sequence Thread] Checking PHD2 state...
[10/10/19 03:46:22.459][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding
[10/10/19 03:46:22.459][DEBUG] [Sequence Thread] Sending to PHD2:
{"method": "get_app_state", "id": 1001}

[10/10/19 03:46:22.561][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[10/10/19 03:46:22.561][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state
[10/10/19 03:46:22.561][DEBUG] [Sequence Thread] EventMarker 171 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:22.564][DEBUG] [Sequence Thread] EventMarker 18 - t:NGC7380 (0); e:0; f:20; o:
[10/10/19 03:46:22.572][DEBUG] [Sequence Thread] EventMarker 19 - t:NGC7380 (0); e:0; f:21; o:
[10/10/19 03:46:22.572][DEBUG] [Sequence Thread] EventMarker 20 - t:NGC7380 (0); e:0; f:21; o:
[10/10/19 03:46:22.572][DEBUG] [Sequence Thread] Running post event...
[10/10/19 03:46:22.572][DEBUG] [Sequence Thread] EventMarker 21 - t:NGC7380 (0); e:0; f:21; o:
[10/10/19 03:46:22.576][DEBUG] [Sequence Thread] Incremented numComplete (21) for event[0]...
[10/10/19 03:46:22.582][DEBUG] [Sequence Thread] Attempting to find next event...
[10/10/19 03:46:22.582][DEBUG] [Sequence Thread] Current event[0] frame count: 21/36...
[10/10/19 03:46:22.582][DEBUG] [Sequence Thread] Current event event[0] has remaining frames.  Returning current event.
[10/10/19 03:46:22.582][DEBUG] [Sequence Thread] Checking for auto manual focus (post)...
[10/10/19 03:46:22.583][DEBUG] [Main Thread] PopulateDataModel:  Transferring view to the data model...
[10/10/19 03:46:22.588][DEBUG] [Sequence Thread] Attempting to move to next event...
[10/10/19 03:46:22.588][DEBUG] [Sequence Thread] Attempting to find next event...
[10/10/19 03:46:22.588][DEBUG] [Sequence Thread] Current event[0] frame count: 21/36...
[10/10/19 03:46:22.588][DEBUG] [Sequence Thread] Current event event[0] has remaining frames.  Returning current event.
[10/10/19 03:46:22.588][DEBUG] [Sequence Thread] Running pre event...
[10/10/19 03:46:22.614][DEBUG] [Sequence Thread] Current target has end time at 10/10/2019 03:57:00
[10/10/19 03:46:22.614][DEBUG] [Sequence Thread] End time of next frame is at 10/10/2019 04:01:22
[10/10/19 03:46:22.614][DEBUG] [Main Thread] PopulateDataModel:  Transferring view to the data model...
[10/10/19 03:46:22.620][DEBUG] [Sequence Thread] Performing serialize...
[10/10/19 03:46:22.628][DEBUG] [Sequence Thread] Serialization took 7 msec.
[10/10/19 03:46:22.628][DEBUG] [Sequence Thread] Target past specified end time, moving to next target...
[10/10/19 03:46:22.628][DEBUG] [Sequence Thread] Run event requested sequence break due to time limit, moving to next target...
[10/10/19 03:46:22.628][DEBUG] [Main Thread] PopulateDataModel:  Transferring view to the data model...
[10/10/19 03:46:22.633][DEBUG] [Main Thread] Adding sequence level notification: Target terminated early due to target end time constraint...
[10/10/19 03:46:22.636][DEBUG] [Sequence Thread] Sending Notification: Status - Target terminated early due to target end time constraint...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Attempting to move to next event...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Finding first active group...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Looking at target NGC7380...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Target is active: True
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Target is complete: False
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Target marked as past end time: True
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Target has end time: True
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Target end time: 10/10/2019 03:57:00
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Sequence complete.
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] No valid targets remain, aborting...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] ********* Run post sequence *********
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] SGPro capture cal frame mode is OFF...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Clearing timed monitoring events...
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] Checking RunEndOfSequenceEquipmentOptions, force = True
[10/10/19 03:46:22.677][DEBUG] [Sequence Thread] In RunEndOfSequenceEquipmentOptions
[10/10/19 03:46:22.684][DEBUG] [Sequence Thread] User prevented end of sequence actions from running.
[10/10/19 03:46:22.688][DEBUG] [Main Thread] Aborting sequence timer thread...
[10/10/19 03:46:22.688][DEBUG] [Main Thread] Sending sequence end notification...
[10/10/19 03:46:22.688][DEBUG] [Main Thread] Adding sequence level notification: Sequence has ended
[10/10/19 03:46:22.691][DEBUG] [Main Thread] Sending Notification: Status - Sequence has ended
[10/10/19 03:46:22.691][DEBUG] [Main Thread] Resetting UI elements...
[10/10/19 03:46:22.691][DEBUG] [Main Thread] Checking if sequence has work left...
[10/10/19 03:46:22.691][DEBUG] [Main Thread] No work left...
[10/10/19 03:46:22.691][DEBUG] [Main Thread] Updating big status...
[10/10/19 03:46:22.691][DEBUG] [Main Thread] Enabling menu items...
[10/10/19 03:46:22.734][DEBUG] [Main Thread] Set target icons...
[10/10/19 03:46:22.743][DEBUG] [Main Thread] Removing camera protection...
[10/10/19 03:46:22.752][DEBUG] [Main Thread] Attempting to find next event...
[10/10/19 03:46:22.753][DEBUG] [Main Thread] Error in GetNextEvent: Index was out of range. Must be non-negative and less than the size of the collection.
Parameter name: index
[10/10/19 03:46:22.804][DEBUG] [Main Thread] Monitoring system shutdown...
[10/10/19 03:46:22.805][DEBUG] [Main Thread] SequenceEnds complete...
[10/10/19 03:46:22.805][DEBUG] [Sequence Thread] Restoring system standby state...