Aborting sequence does not stop the autoguider


#1

running v2.4.0.2618

At the end of my imaging session I do: Pause Sequence, select abort the current image and select the option to “Run end of sequence actions”. Although most actions do run (camera warm-up, park mount), the auto guider is not stopped even though I have selected the Auto Guide option “Stop when sequence completes”.

This has reproduced each of the last several imaging sessions.

Log file: http://adgsoftware.com/misc/sg_logfile_20141231174345.zip

Andy


#2

I am unable to reproduce this. Following the steps you outlined above (using PHD 2.4.1)

[1/1/2015 2:54:21 PM] [DEBUG] [Main Thread] User requested sequence pause...
[1/1/2015 2:54:27 PM] [DEBUG] [Sequence Thread] Sequence pause detected...
[1/1/2015 2:54:27 PM] [DEBUG] [Sequence Thread] RunPostEvent requested sequence break...
[1/1/2015 2:54:56 PM] [DEBUG] [Sequence Thread] Stopping auto guiding...

And from your logs, this operation also looks like it was successful (guiding stops at 1:21:14):

[1/1/2015 1:20:44 AM] [DEBUG] [Main Thread] User requested sequence abort...
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] Run event requested sequence abort...
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] Clearing timed monitoring events...
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] Checking RunEndOfSequenceEquipmentOptions, force = True
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] In RunEndOfSequenceEquipmentOptions
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] Stopping auto guiding...
[1/1/2015 1:20:44 AM] [DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received...
[1/1/2015 1:20:44 AM] [DEBUG] [TEC Thread] TEC Change: Starting...
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] Parking telescope...
[1/1/2015 1:20:44 AM] [DEBUG] [Sequence Thread] ASCOM Telescope: Park message received.
[1/1/2015 1:20:44 AM] [DEBUG] [TEC Thread] TEC Change: Changing temp from -30.00 to 20.00 in 240 seconds...
[1/1/2015 1:20:46 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"GuideStep","Timestamp":1420093246.189,"Host":"AGALASSO","Inst":1,"Frame":2120,"Time":3721.957,"Mount":"Gemini Telescope .NET","dx":-0.694,"dy":0.323,"RADistanceRaw":0.721,"DECDistanceRaw":-0.270,"RADistanceGuide":0.320,"DECDistanceGuide":-0.270,"StarMass":102897,"SNR":39.58,"AvgDist":0.51}
[1/1/2015 1:20:47 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"GuideStep","Timestamp":1420093247.821,"Host":"AGALASSO","Inst":1,"Frame":2121,"Time":3723.589,"Mount":"Gemini Telescope .NET","dx":-7.834,"dy":0.689,"RADistanceRaw":7.865,"DECDistanceRaw":-0.097,"RADistanceGuide":3.707,"DECDistanceGuide":0.000,"StarMass":106288,"SNR":26.97,"AvgDist":2.72}
[1/1/2015 1:20:49 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"GuideStep","Timestamp":1420093249.474,"Host":"AGALASSO","Inst":1,"Frame":2122,"Time":3725.242,"Mount":"Gemini Telescope .NET","dx":-15.423,"dy":1.671,"RADistanceRaw":15.512,"DECDistanceRaw":-0.505,"RADistanceGuide":7.787,"DECDistanceGuide":-0.505,"StarMass":103565,"SNR":32.28,"AvgDist":6.55,"RALimited":true}
[1/1/2015 1:20:51 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"GuideStep","Timestamp":1420093251.125,"Host":"AGALASSO","Inst":1,"Frame":2123,"Time":3726.893,"Mount":"Gemini Telescope .NET","dx":-21.458,"dy":1.841,"RADistanceRaw":21.537,"DECDistanceRaw":-0.220,"RADistanceGuide":11.222,"DECDistanceGuide":-0.220,"StarMass":111149,"SNR":27.35,"AvgDist":11.05,"RALimited":true}
[1/1/2015 1:20:52 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"GuideStep","Timestamp":1420093252.753,"Host":"AGALASSO","Inst":1,"Frame":2124,"Time":3728.521,"Mount":"Gemini Telescope .NET","dx":-29.022,"dy":1.099,"RADistanceRaw":29.001,"DECDistanceRaw":1.090,"RADistanceGuide":15.225,"DECDistanceGuide":0.000,"StarMass":115065,"SNR":25.40,"AvgDist":16.45,"RALimited":true}
[1/1/2015 1:20:53 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"Event":"LockPositionLost","Timestamp":1420093253.857,"Host":"AGALASSO","Inst":1}
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Sending: {"method": "get_app_state", "id": 1001}

[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Sending: {"method": "get_app_state", "id": 1001}

[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding
[1/1/2015 1:21:03 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[1/1/2015 1:21:04 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Sending: {"method": "get_app_state", "id": 1001}

[1/1/2015 1:21:04 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding
[1/1/2015 1:21:04 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Guiding
[1/1/2015 1:21:04 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Received: {"jsonrpc":"2.0","result":"Stopped","id":1001}
[1/1/2015 1:21:04 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Sending: {"method": "get_app_state", "id": 1001}

[1/1/2015 1:21:14 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped
[1/1/2015 1:21:14 AM] [DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped
[1/1/2015 1:21:14 AM] [DEBUG] [PHD2 Listener Thread] PHD2 Sending: {"method": "get_app_state", "id": 1001}

#3

Yeah, that was from me manually stopping it.

SGP parked the scope without stopping guiding around 1:20:46. From the PHD2 log http://adgsoftware.com/misc/PHD2_DebugLog_2014-12-31_200809.zip :

01:20:46.154 00.236 4288 Error thrown from scope_ascom.cpp:643->ASCOM Scope: pulseguide command failed: (ASCOM.GeminiTelescope.Telescope) Invalid while parked

Here are all the server commands in the log file in that time frame, no stop_capture was received:

$ grep evsrv PHD2_DebugLog_2014-12-31_200809.txt | tail -35
00:49:43.964 00.000 6984 evsrv: {"Event":"Settling","Timestamp":1420091383.964,"Host":"AGALASSO","Inst":1,"Distance":0.55,"Time":7.2,"SettleTime":8.0}
00:49:45.720 00.000 6984 evsrv: {"Event":"SettleDone","Timestamp":1420091385.720,"Host":"AGALASSO","Inst":1,"Status":0}
01:20:02.677 01.570 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:02.677 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:06.030 01.405 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:06.030 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:07.029 00.667 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:07.029 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:08.029 00.056 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:08.029 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:09.039 01.010 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:09.039 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:10.039 00.325 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:10.039 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:11.040 01.001 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:11.040 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:11.090 00.050 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:11.090 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:11.140 00.050 6984 evsrv: cli 03FE5F08 request: {"method":"dither","params":[2,false,{"pixels":1,"time":8,"timeout":300}],"id":1002}
01:20:11.142 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":0,"id":1002}
01:20:11.331 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093211.330,"Host":"AGALASSO","Inst":1,"Distance":1.71,"Time":0.0,"SettleTime":8.0}
01:20:11.344 00.000 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:11.345 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:11.440 00.095 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:20:11.440 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Guiding","id":1001}
01:20:13.969 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093213.969,"Host":"AGALASSO","Inst":1,"Distance":1.20,"Time":0.0,"SettleTime":8.0}
01:20:16.003 00.000 6984 evsrv: {"Event":"Settling","Timestamp":1420093216.003,"Host":"AGALASSO","Inst":1,"Distance":1.13,"Time":0.0,"SettleTime":8.0}
01:20:17.960 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093217.960,"Host":"AGALASSO","Inst":1,"Distance":1.08,"Time":0.0,"SettleTime":8.0}
01:20:20.099 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093220.099,"Host":"AGALASSO","Inst":1,"Distance":0.98,"Time":0.0,"SettleTime":8.0}
01:20:22.195 00.000 6984 evsrv: {"Event":"Settling","Timestamp":1420093222.195,"Host":"AGALASSO","Inst":1,"Distance":0.87,"Time":2.1,"SettleTime":8.0}
01:20:24.215 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093224.214,"Host":"AGALASSO","Inst":1,"Distance":0.80,"Time":4.1,"SettleTime":8.0}
01:20:26.117 00.001 6984 evsrv: {"Event":"Settling","Timestamp":1420093226.117,"Host":"AGALASSO","Inst":1,"Distance":0.76,"Time":6.0,"SettleTime":8.0}
01:20:28.145 00.000 6984 evsrv: {"Event":"SettleDone","Timestamp":1420093228.145,"Host":"AGALASSO","Inst":1,"Status":0}
01:21:04.062 00.002 6984 evsrv: cli 03FE5F08 request: {"method":"get_app_state","id":1001}
01:21:04.062 00.000 6984 evsrv: cli 03FE5F08 response: {"jsonrpc":"2.0","result":"Stopped","id":1001}

#4

OK, thx. I think I found the issue. Not 100% sure since it seems to be a timing issue.


#5

Excellent, thanks Ken!


www.mainsequencesoftware.com