[06/14/18 03:14:42.771][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [06/14/18 03:14:42.771][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [06/14/18 03:14:42.771][DEBUG] [Sequence Thread] EventMarker 18 - t:v0347 Dra (0); e:2; f:36; o: [06/14/18 03:14:42.778][DEBUG] [Sequence Thread] EventMarker 19 - t:v0347 Dra (0); e:2; f:37; o: [06/14/18 03:14:42.778][DEBUG] [Sequence Thread] EventMarker 20 - t:v0347 Dra (0); e:2; f:37; o: [06/14/18 03:14:42.778][DEBUG] [Sequence Thread] Running post event... [06/14/18 03:14:42.778][DEBUG] [Sequence Thread] EventMarker 21 - t:v0347 Dra (0); e:2; f:37; o: [06/14/18 03:14:42.780][DEBUG] [Sequence Thread] Incremented numComplete (37) for event[2]... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Attempting to find next event... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Current event[2] frame count: 37/200... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Looking at event[3]... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Looking at event[4]... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Looking at event[0]... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Found event event[0] with remaining frames. [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Event[0] frame count: 37/200... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Getting next event (0)... [06/14/18 03:14:42.782][DEBUG] [Sequence Thread] Checking for auto manual focus (post)... [06/14/18 03:14:42.782][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Attempting to move to next event... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Attempting to find next event... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Current event[2] frame count: 37/200... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Looking at event[3]... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Looking at event[4]... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Looking at event[0]... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Found event event[0] with remaining frames. [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Event[0] frame count: 37/200... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Getting next event (0)... [06/14/18 03:14:42.791][DEBUG] [Sequence Thread] Running pre event... [06/14/18 03:14:42.819][DEBUG] [Sequence Thread] Running capture event... [06/14/18 03:14:42.842][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [06/14/18 03:14:42.842][DEBUG] [Sequence Thread] Set start frame temp to 22.3333333333333... [06/14/18 03:14:42.843][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] Sending commands... [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] EventMarker 2 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] Checking if Meridian Flip is needed [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] Meridian flip not needed, telescope on East side [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] EventMarker 3 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:42.845][DEBUG] [Sequence Thread] Waiting for AF to complete... [06/14/18 03:14:42.846][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [06/14/18 03:14:42.846][DEBUG] [Camera Thread] Checking for auto focus... [06/14/18 03:14:42.846][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [06/14/18 03:14:43.046][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [06/14/18 03:14:43.046][DEBUG] [Sequence Thread] EventMarker 4 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:43.046][DEBUG] [Sequence Thread] Setting filter position 1... [06/14/18 03:14:43.054][DEBUG] [Sequence Thread] Setting filter: B... [06/14/18 03:14:43.054][DEBUG] [Sequence Thread] Moving filter wheel, isMoving, check 1... [06/14/18 03:14:43.054][DEBUG] [Sequence Thread] Moving filter wheel, isMoving, check 1 is complete... [06/14/18 03:14:43.056][DEBUG] [Sequence Thread] Moving filter wheel, isMoving, check 2... [06/14/18 03:14:54.064][DEBUG] [Sequence Thread] Moving filter wheel, isMoving, check 2 complete... [06/14/18 03:14:58.079][DEBUG] [Sequence Thread] EventMarker 5 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:58.079][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [06/14/18 03:14:58.079][DEBUG] [Sequence Thread] EventMarker 6 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] Created base name for frame capture: v0347 Dra_B_120sec_2x2_8C_2018-06-14_031458_frame38 [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\User\Documents\SS_Images\Light\ [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] EventMarker 1 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] EventMarker 7 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] Checking PHD2 state... [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [06/14/18 03:14:58.081][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 1, "time": 5, "timeout": 600}, false ], "id": 1003} [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 1, "time": 5, "timeout": 600}, false ], "id": 1003} [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] Checking PHD2 state... [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [06/14/18 03:14:58.182][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [06/14/18 03:14:58.283][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [06/14/18 03:14:58.283][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 1 [06/14/18 03:14:58.283][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:14:58.283][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:14:58.290][DEBUG] [Sequence Thread] Distance is below 1, starting timer... [06/14/18 03:14:58.792][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:14:58.792][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:14:59.300][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:14:59.300][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:14:59.804][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:14:59.804][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:00.312][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:00.312][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:00.816][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:00.816][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:01.323][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:01.323][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:01.828][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:01.828][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:02.336][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:02.336][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:02.840][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:02.840][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:03.349][DEBUG] [Sequence Thread] PHD2 distance request... [06/14/18 03:15:03.349][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [06/14/18 03:15:03.353][DEBUG] [Sequence Thread] Distance stayed below 1 for 5 seconds, done settling... [06/14/18 03:15:03.353][DEBUG] [Sequence Thread] SGPro settle criteria have been met, but the guider reports it is not ready to resume, waiting... [06/14/18 03:15:06.718][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] Auto guider has settled... [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] EventMarker 8 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] EventMarker 9 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] EventMarker 10 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [06/14/18 03:15:06.864][DEBUG] [Sequence Thread] EventMarker 11 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.865][DEBUG] [Sequence Thread] EventMarker 12 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.866][DEBUG] [Sequence Thread] EventMarker 13 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:15:06.867][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [06/14/18 03:15:06.869][DEBUG] [Camera Thread] SetAscomNormalSpeed... [06/14/18 03:15:06.869][DEBUG] [Camera Thread] Readout speed set to NORMAL... [06/14/18 03:15:38.180][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [06/14/18 03:15:38.189][DEBUG] [MF Update Thread] Performing serialize... [06/14/18 03:16:18.681][DEBUG] [TEC Thread] TEC Change: Complete... [06/14/18 03:16:19.056][DEBUG] [Main Thread] Turning camera cooler off... [06/14/18 03:16:19.219][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [06/14/18 03:16:19.442][DEBUG] [Main Thread] Turning camera cooler off... [06/14/18 03:17:06.867][DEBUG] [Sequence Thread] EventMarker 15 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:17:06.870][DEBUG] [Sequence Thread] Waking from exposure time sleep period... [06/14/18 03:17:06.870][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp... [06/14/18 03:17:06.870][DEBUG] [Sequence Thread] Saving the CCD temp... [06/14/18 03:17:06.880][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion... [06/14/18 03:17:06.883][DEBUG] [Sequence Thread] EventMarker 16 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:17:09.687][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [06/14/18 03:17:09.893][DEBUG] [Sequence Thread] EventMarker 17 - t:v0347 Dra (0); e:0; f:37; o: [06/14/18 03:17:09.893][DEBUG] [Sequence Thread] Image reported as complete. Continuing... [06/14/18 03:17:09.894][DEBUG] [Sequence Thread] Collecting FITs headers... [06/14/18 03:17:09.895][DEBUG] [Sequence Thread] ASCOM Camera: Could not get last exposure start time. Reported as (unknown) [06/14/18 03:17:09.895][DEBUG] [Sequence Thread] DATE-LOC time provided by SGPro (failed to retrieve valid entry from camera)... [06/14/18 03:17:09.900][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info for last solve... [06/14/18 03:17:09.900][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI... [06/14/18 03:17:09.900][DEBUG] [Sequence Thread] Clearing timed monitoring events... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\User\Documents\SS_Images\Light\v0347 Dra_B_120sec_2x2_8C_2018-06-14_031458_frame38.fit [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Start... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Checking image data... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Create normal preview bitmap... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Locking preview bits... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Validating file name... [06/14/18 03:17:09.903][DEBUG] [Sequence Thread] SaveFileAscom: Starting byte traversal... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileAscom: Unlocking preview bits... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileAscom: Saving preview bitmap to disk... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers... [06/14/18 03:17:10.042][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data... [06/14/18 03:17:10.056][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill... [06/14/18 03:17:10.056][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap... [06/14/18 03:17:10.056][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream [06/14/18 03:17:10.062][DEBUG] [Sequence Thread] SaveFileAscom: Displaying image preview in UI... [06/14/18 03:17:10.062][DEBUG] [Sequence Thread] Internal Interface: Set Preview... [06/14/18 03:17:10.062][DEBUG] [Sequence Thread] Display image preview using asynch task... [06/14/18 03:17:10.242][DEBUG] [Sequence Thread] SaveFileAscom: Done [06/14/18 03:17:10.242][DEBUG] [Sequence Thread] =========== Save file took 341 ms [06/14/18 03:17:10.247][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [06/14/18 03:17:10.247][DEBUG] [Sequence Thread] Checking PHD2 state... [06/14/18 03:17:10.247][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [06/14/18 03:17:10.247][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001}