View Issue Details

IDProjectCategoryView StatusLast Update
0002032CCdciel[All Projects] Generalpublic18-11-30 10:20
ReporterCedric RaguenaudAssigned ToPatrick Chevalley 
PrioritynormalSeveritymajorReproducibilityhave not tried
Status closedResolutionduplicate 
Product Version 
Target Version1.0Fixed in Version 
Summary0002032: Multiple crashes
DescriptionLast night, I experienced multiple crashes with a window saying "TBGRawWinBitmap.ReallocBitmap: Windows error 8".

I wasn't in debug mode at the time so I don't have much more information. After a couple of those the application started misbehaving and I had to shut it down and restart it.
TagsNo tags attached.

Relationships

duplicate of 0002033 resolvedPatrick Chevalley Application crash 

Activities

Cedric Raguenaud

18-11-20 14:51

reporter  

Patrick Chevalley

18-11-20 16:53

administrator   ~0005113

"Error 8" is not enough memory.
You have the icon of the Task Manager in the task bar. Do you see anything abnormal here?

I regularly check ccdciel for memory leak with fpc heaptrc so I am confident there is no problem at this level.

Now I am looping in preview with the Ascom camera simulator set to an image size of 4000x3000, the task manager show ccdciel memory usage to vary from 200MB to 350MB, depending if the capture is in progress or if the image is downloading, with no increase with time.

Can you do the same with your camera and check if there is a memory increase?

Patrick Chevalley

18-11-20 23:04

administrator   ~0005123

I add a small program to test the camera out of ccdciel but using the same function.
This may help to get more information about this memory problem.

This program is now it the git source or you can download my compiled version here : test_ascom_camera.zip

When it run the program display it's heap size. It is interesting to compare with the process memory size in the task manager, a part of the difference is the memory taken by the ascom driver. Be sure to show the process detail in the task manager as in the screenshot.

test_ascom_camera.zip (1,127,312 bytes)
test_camera.png (61,969 bytes)
test_camera.png (61,969 bytes)

Cedric Raguenaud

18-11-21 09:13

reporter   ~0005124

When I had the last crashes, the memory size for the process didn't increase significantly. I'll run your program.

Patrick Chevalley

18-11-21 10:08

administrator   ~0005125

Also can you upload the log for the session you get this "Windows error 8"?
Because there is two possible reason for this error,
1) memory is really exhausted by something that not release it correctly
2) a wrong image size is set, making it to try to allocate an image of MaxInteger x MaxInteger for example.

Cedric Raguenaud

18-11-21 10:44

reporter   ~0005126

I've run it for 608 images and it didn't crash, didn't show any significant memory usage (both within the program and via task manager. To me, that plus where the error is reported, points to a problem unrelated to taking the images.

Cedric Raguenaud

18-11-21 10:48

reporter   ~0005127

Here is an example from last night. Unfortunately, because the application crashed and exited, nothing appears in the log.

Faulting application name: ccdciel.exe, version: 0.0.0.0, time stamp: 0x00000000
Faulting module name: mswsock.dll, version: 10.0.17134.1, time stamp: 0xd0d9b906
Exception code: 0xc0000409
Fault offset: 0x0000dc07
Faulting process ID: 0x12dc
Faulting application start time: 0x01d4813b2d533e53
Faulting application path: C:\Program Files (x86)\CCDciel\ccdciel.exe
Faulting module path: C:\WINDOWS\system32\mswsock.dll
Report ID: 9a008655-46b9-4d51-aeab-a14ccf8410af
Faulting package full name:
Faulting package-relative application ID:

Log:
2018-11-21T02:40:26.821 CCDciel Version beta 0.9.48-1243-77d8fb6 2018/11/19 15:55:05
2018-11-21T02:40:26.821 Compiled with: Lazarus 2.0.0.2 Free Pascal 3.0.4 Win32-i386-win32
2018-11-21T02:40:26.825 1: CCDciel Version beta 0.9.48-1243-77d8fb6 initialized
2018-11-21T02:40:26.825 3: Using configuration file ccdciel.conf
2018-11-21T02:40:27.644 1: Run sequence k:\astro\ccdciel\test.targets
2018-11-21T02:40:30.220 1: Camera connected
2018-11-21T02:40:31.242 3: ASCOM.QHYCCD.Camera: Set temperature -10.0
2018-11-21T02:40:31.245 3: ASCOM.QHYCCD.Camera: Set cooler : True
2018-11-21T02:40:31.250 3: ASCOM.QHYCCD.Camera: Connected
2018-11-21T02:40:31.833 3: ASCOM.HomeMade.Focuser: Connected
2018-11-21T02:40:31.835 1: Focuser connected
2018-11-21T02:40:31.855 3: EQMOD.Telescope: Connected
2018-11-21T02:40:31.857 3: EQMOD.Telescope: Mount capabilities: EQmod CanPark CanSlew CanSlewAsync CanSync CanSetTracking
2018-11-21T02:40:31.860 1: Mount connected
2018-11-21T02:40:32.267 2: Camera cooler : True
2018-11-21T02:40:32.283 2: Camera frame x=0 y=0 width=5544 height=3684
2018-11-21T02:40:33.094 1: Starting sequence
2018-11-21T02:40:33.096 1: The telescope is parked, unpark now
2018-11-21T02:40:33.100 3: EQMOD.Telescope: Unpark
2018-11-21T02:40:38.267 1: Try to connect to autoguider
2018-11-21T02:40:38.270 1: Autoguider: Connecting
2018-11-21T02:40:38.285 2: Autoguider: Stopped
2018-11-21T02:40:48.268 1: Starting sequence
2018-11-21T02:40:59.459 3: CCD temperature: -10.0
2018-11-21T02:40:59.462 1: Wait to start sequence test at 20:00:00
2018-11-21T02:40:59.465 1: Need to wait until 20:00:00
2018-11-21T02:40:59.467 1: Pause: Need to wait until 20:00:00
2018-11-21T02:41:25.665 1: The sequence test will be stopped at 06:24:53, in 13407 seconds.
2018-11-21T02:41:25.667 1: Starting sequence test
2018-11-21T02:41:25.683 1: Initialise target AT2018ipd
2018-11-21T02:41:25.685 2: Target AT2018ipd canceled before start, Stop time 23:19:54 already passed
2018-11-21T02:41:25.688 0: AT2018ipd, Target initialisation failed!
2018-11-21T02:41:25.709 1: Initialise target AT2018ipo
2018-11-21T02:41:25.712 3: Target can run until 07:38:32, in 17827 seconds
2018-11-21T02:41:25.714 2: Stop autoguider
2018-11-21T02:41:28.254 3: EQMOD.Telescope: Slewing to 02h28m03s/+72d36m00s
2018-11-21T02:41:28.581 0: EQMOD.Telescope: Slew error: SlewToCoordinatesAsync() is not permitted while mount is parked or parking.
2018-11-21T02:41:43.755 1: Object AT2018ipo, start plan 6x300s
2018-11-21T02:41:48.776 1: Start step 6x300s
2018-11-21T02:41:48.778 2: Start capture
2018-11-21T02:41:48.781 1: Autofocus now
2018-11-21T02:41:48.783 2: Get current position from current target
2018-11-21T02:41:48.803 2: Slew to focus star HIP 12448
2018-11-21T02:41:48.809 0: EQMOD.Telescope: Set tracking error: Tracking change is not permitted while mount is parked or parking.
2018-11-21T02:41:48.812 3: EQMOD.Telescope: Slewing to 02h40m19s/+72d18m14s
2018-11-21T02:41:48.817 0: EQMOD.Telescope: Slew error: SlewToCoordinatesAsync() is not permitted while mount is parked or parking.
2018-11-21T02:41:48.827 0: Precision slew failed!
2018-11-21T02:41:49.959 2: Focuser temperature: -1.6
2018-11-21T02:41:49.966 3: ASCOM.QHYCCD.Camera: Set binning 2x2
2018-11-21T02:41:50.294 2: Camera frame x=0 y=0 width=2772 height=1842
2018-11-21T02:41:52.063 3: Take control exposure for 5.0 seconds
2018-11-21T02:42:04.363 2: Camera frame x=634 y=1270 width=400 height=400
2018-11-21T02:42:04.776 2: Autofocus start Dynamic curve
2018-11-21T02:42:04.798 2: Focuser temperature: -1.6
2018-11-21T02:42:04.807 2: AutoFocus started, initial position: 21084
2018-11-21T02:42:13.282 3: Autofocus running, hfd=1.5 peak:8480.5 snr:60.7
2018-11-21T02:42:13.301 3: ASCOM.HomeMade.Focuser: Focuser move to 22191 + 5 Backlash compensation
2018-11-21T02:42:29.266 3: ASCOM.HomeMade.Focuser: Focuser move to 22191
2018-11-21T02:42:33.442 1: Request to stop the current sequence
2018-11-21T02:42:33.454 1: Request to stop autofocus ...
2018-11-21T02:42:33.473 3: ASCOM.QHYCCD.Camera: Abort exposure
2018-11-21T02:42:35.487 3: EQMOD.Telescope: Stop telescope motion.
2018-11-21T02:42:35.573 3: ASCOM.QHYCCD.Camera: Abort exposure
2018-11-21T02:42:37.583 2: Abort exposure
2018-11-21T02:42:37.593 2: Stop capture
2018-11-21T02:42:37.603 2: Stop autoguider
2018-11-21T02:42:37.993 1: Executing the termination actions.
2018-11-21T02:42:38.004 3: EQMOD.Telescope: Park
2018-11-21T02:42:38.239 3: ASCOM.QHYCCD.Camera: Abort exposure
2018-11-21T02:42:38.249 3: ASCOM.QHYCCD.Camera: Set binning 1x1
2018-11-21T02:42:38.372 2: Camera frame x=1268 y=2540 width=800 height=800
2018-11-21T02:42:41.368 2: Camera frame x=0 y=0 width=5544 height=3684
2018-11-21T02:42:41.480 1: AutoFocus error
2018-11-21T02:42:41.500 2: Return the focuser to previous position 21084
2018-11-21T02:42:41.514 3: ASCOM.HomeMade.Focuser: Focuser move to 21084
2018-11-21T02:42:57.386 1: Autofocus failed!
2018-11-21T02:42:57.402 0: Cannot start capture now
2018-11-21T02:42:57.412 2: Stop capture
2018-11-21T02:43:00.477 1: Sequence test stopped.
2018-11-21T02:43:00.503 1: Plan 6x300s stopped.
2018-11-21T02:43:08.644 1: Starting sequence
2018-11-21T02:43:12.223 2: End preview
2018-11-21T02:43:23.047 3: CCD temperature: -9.7
2018-11-21T02:43:23.073 1: Wait to start sequence test at 20:00:00
2018-11-21T02:43:23.084 1: Need to wait until 20:00:00
2018-11-21T02:43:23.105 1: Pause: Need to wait until 20:00:00
2018-11-21T02:43:27.952 1: The sequence test will be stopped at 06:24:53, in 13285 seconds.
2018-11-21T02:43:27.967 1: Starting sequence test
2018-11-21T02:43:28.411 1: Initialise target AT2018ipd
2018-11-21T02:43:28.435 2: Target AT2018ipd canceled before start, Stop time 23:19:54 already passed
2018-11-21T02:43:28.447 0: AT2018ipd, Target initialisation failed!
2018-11-21T02:43:28.478 1: Initialise target AT2018ipo
2018-11-21T02:43:28.490 3: Target can run until 07:38:32, in 17704 seconds
2018-11-21T02:43:28.502 2: Stop autoguider
2018-11-21T02:43:30.857 3: EQMOD.Telescope: Slewing to 02h28m03s/+72d36m00s
2018-11-21T02:43:51.290 3: EQMOD.Telescope: Slew is complete
2018-11-21T02:44:10.348 1: Object AT2018ipo, start plan 6x300s
2018-11-21T02:44:15.403 1: Start step 6x300s
2018-11-21T02:44:15.413 2: Start capture
2018-11-21T02:44:15.433 1: Autofocus now
2018-11-21T02:44:15.443 2: Get current position from current target
2018-11-21T02:44:15.472 2: Slew to focus star HIP 12448
2018-11-21T02:44:15.492 3: EQMOD.Telescope: Slewing to 02h40m19s/+72d18m14s
2018-11-21T02:44:23.972 3: EQMOD.Telescope: Slew is complete
2018-11-21T02:44:34.028 3: Take control exposure for 10.0 seconds
2018-11-21T02:45:05.423 3: Resolve control exposure
2018-11-21T02:45:05.979 3: Resolving using PlateSolve ...
2018-11-21T02:45:24.154 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T02:45:24.165 2: PlateSolve resolve successful. Solved in 18 seconds , offset 0.1587 degree
2018-11-21T02:45:24.194 3: Distance to target: 9.53111 arcmin
2018-11-21T02:45:24.219 3: EQMOD.Telescope: Sync to 02h41m38s/+72d10m48s
2018-11-21T02:45:26.334 3: EQMOD.Telescope: Slewing to 02h40m19s/+72d18m14s
2018-11-21T02:45:30.019 3: EQMOD.Telescope: Slew is complete
2018-11-21T02:45:40.096 3: Take control exposure for 10.0 seconds
2018-11-21T02:46:02.762 3: Resolve control exposure
2018-11-21T02:46:03.300 3: Resolving using PlateSolve ...
2018-11-21T02:46:21.341 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T02:46:21.353 2: PlateSolve resolve successful. Solved in 18 seconds , offset 0.0231 degree
2018-11-21T02:46:21.384 3: Distance to target: 1.39072 arcmin
2018-11-21T02:46:21.398 2: Precision slew finished.
2018-11-21T02:46:22.494 2: Focuser temperature: -1.7
2018-11-21T02:46:22.507 3: ASCOM.QHYCCD.Camera: Set binning 2x2
2018-11-21T02:46:23.407 2: Camera frame x=0 y=0 width=2772 height=1842
2018-11-21T02:46:24.606 3: Take control exposure for 5.0 seconds
2018-11-21T02:46:50.357 2: Camera frame x=1266 y=692 width=400 height=400
2018-11-21T02:46:50.369 2: Autofocus start Dynamic curve
2018-11-21T02:46:50.396 2: Focuser temperature: -1.7
2018-11-21T02:46:50.409 2: AutoFocus started, initial position: 21084
2018-11-21T02:47:09.039 3: Autofocus running, hfd=13.0 peak:5699.0 snr:44.8
2018-11-21T02:47:09.063 3: ASCOM.HomeMade.Focuser: Focuser move to 22191 + 5 Backlash compensation
2018-11-21T02:47:26.131 3: ASCOM.HomeMade.Focuser: Focuser move to 22191
2018-11-21T02:47:37.148 3: Autofocus mean frame 1/2, hfd=11.7 peak:14068.3 snr:89.0
2018-11-21T02:47:46.063 3: Autofocus mean frame 2/2, hfd=12.0 peak:13443.0 snr:86.9
2018-11-21T02:47:46.086 3: Autofocus running, hfd=11.8 peak:13443.0 snr:86.9
2018-11-21T02:47:46.116 3: ASCOM.HomeMade.Focuser: Focuser move to 21822
2018-11-21T02:48:00.583 3: Autofocus mean frame 1/2, hfd=8.8 peak:22555.8 snr:124.1
2018-11-21T02:48:09.393 3: Autofocus mean frame 2/2, hfd=8.8 peak:22787.4 snr:125.0
2018-11-21T02:48:09.418 3: Autofocus running, hfd=8.8 peak:22555.8 snr:124.1
2018-11-21T02:48:09.448 3: ASCOM.HomeMade.Focuser: Focuser move to 21453
2018-11-21T02:48:23.791 3: Autofocus mean frame 1/2, hfd=6.7 peak:36137.4 snr:167.4
2018-11-21T02:48:32.376 3: Autofocus mean frame 2/2, hfd=6.0 peak:46387.9 snr:194.6
2018-11-21T02:48:32.392 3: Autofocus running, hfd=6.4 peak:36137.4 snr:167.4
2018-11-21T02:48:32.422 3: ASCOM.HomeMade.Focuser: Focuser move to 21084
2018-11-21T02:48:46.862 3: Autofocus mean frame 1/2, hfd=4.9 peak:60294.1 snr:226.6
2018-11-21T02:48:55.506 3: Autofocus mean frame 2/2, hfd=5.0 peak:60259.7 snr:226.5
2018-11-21T02:48:55.521 3: Autofocus running, hfd=5.0 peak:60259.7 snr:226.5
2018-11-21T02:48:55.547 3: ASCOM.HomeMade.Focuser: Focuser move to 20715
2018-11-21T02:49:09.998 3: Autofocus mean frame 1/2, hfd=5.7 peak:60296.9 snr:226.7
2018-11-21T02:49:18.974 3: Autofocus mean frame 2/2, hfd=5.5 peak:60297.1 snr:226.7
2018-11-21T02:49:18.989 3: Autofocus running, hfd=5.6 peak:60296.9 snr:226.7
2018-11-21T02:49:19.014 3: ASCOM.HomeMade.Focuser: Focuser move to 20346
2018-11-21T02:49:33.368 3: Autofocus mean frame 1/2, hfd=7.3 peak:60312.0 snr:226.7
2018-11-21T02:49:42.075 3: Autofocus mean frame 2/2, hfd=7.2 peak:50271.0 snr:203.9
2018-11-21T02:49:42.105 3: Autofocus running, hfd=7.3 peak:50271.0 snr:203.9
2018-11-21T02:49:42.137 3: ASCOM.HomeMade.Focuser: Focuser move to 19977
2018-11-21T02:49:56.602 3: Autofocus mean frame 1/2, hfd=10.0 peak:20877.3 snr:118.0
2018-11-21T02:50:05.269 3: Autofocus mean frame 2/2, hfd=10.1 peak:20743.8 snr:117.4
2018-11-21T02:50:05.294 3: Autofocus running, hfd=10.0 peak:20743.8 snr:117.4
2018-11-21T02:50:05.360 3: HYPERBOLA curve fitting focus at 4.281, remaining curve fit error 0.0398, iteration cycles 5
2018-11-21T02:50:05.384 3: ASCOM.HomeMade.Focuser: Focuser move to 21349 + 5 Backlash compensation
2018-11-21T02:50:25.248 3: ASCOM.HomeMade.Focuser: Focuser move to 21349
2018-11-21T02:50:27.091 3: ASCOM.HomeMade.Focuser: Focuser move to 20980
2018-11-21T02:50:41.602 2: Autofocus finished, POS=20980 HFD=5.0 PEAK:60314.6 SNR:226.7 TEMP:-1.7
2018-11-21T02:50:41.626 3: ASCOM.QHYCCD.Camera: Set binning 1x1
2018-11-21T02:50:42.429 2: Camera frame x=2532 y=1384 width=800 height=800
2018-11-21T02:50:44.455 2: Camera frame x=0 y=0 width=5544 height=3684
2018-11-21T02:50:44.773 1: AutoFocus successful
2018-11-21T02:50:44.788 2: End preview
2018-11-21T02:50:44.814 2: Return to target position
2018-11-21T02:50:44.843 3: EQMOD.Telescope: Slewing to 02h26m16s/+72d30m55s
2018-11-21T02:50:52.686 3: EQMOD.Telescope: Slew is complete
2018-11-21T02:51:02.776 3: Take control exposure for 10.0 seconds
2018-11-21T02:51:23.252 3: Resolve control exposure
2018-11-21T02:51:23.844 3: Resolving using PlateSolve ...
2018-11-21T02:51:41.106 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T02:51:41.122 2: PlateSolve resolve successful. Solved in 17 seconds , offset 0.0198 degree
2018-11-21T02:51:41.182 3: Distance to target: 1.18914 arcmin
2018-11-21T02:51:41.205 2: Precision slew finished.
2018-11-21T02:51:41.232 2: Restart autoguider
2018-11-21T02:51:49.694 2: Autoguider: Star Selected
2018-11-21T02:51:51.631 2: Autoguider: Looping Exposures
2018-11-21T02:51:57.794 2: Autoguider: Lock Position Set
2018-11-21T02:51:58.074 2: Autoguider: Looping Exposures
2018-11-21T02:52:02.316 2: Autoguider: Start Guiding
2018-11-21T02:52:04.336 2: Autoguider: Settling
2018-11-21T02:52:10.941 2: Autoguider: Settle Done
2018-11-21T02:52:11.271 2: Autoguider: Guiding
2018-11-21T02:52:13.506 1: Starting Light exposure 1/6 for 300 seconds
2018-11-21T02:53:06.563 2: Autoguider: Alert
2018-11-21T02:53:06.587 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T02:53:09.771 2: Autoguider: Guiding
2018-11-21T02:56:22.929 2: Autoguider: Alert
2018-11-21T02:56:22.973 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T02:56:25.965 2: Autoguider: Guiding
2018-11-21T02:57:23.746 2: Autoguider: Alert
2018-11-21T02:57:23.775 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T02:57:24.387 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-9.9C_20181121_015723_11.fits
2018-11-21T02:57:24.554 1: Starting Light exposure 2/6 for 300 seconds
2018-11-21T02:57:25.997 2: Autoguider: Star lost
2018-11-21T02:57:26.029 1: Autoguider: StarLost Star lost - mass changed
2018-11-21T02:57:28.559 2: Autoguider: Guiding
2018-11-21T02:57:54.260 2: Autoguider: Alert
2018-11-21T02:57:54.283 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T02:57:57.227 2: Autoguider: Guiding
2018-11-21T02:58:41.668 2: Autoguider: Alert
2018-11-21T02:58:41.696 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T02:58:44.494 2: Autoguider: Guiding
2018-11-21T03:01:21.304 2: Autoguider: Alert
2018-11-21T03:01:21.330 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:01:24.210 2: Autoguider: Guiding
2018-11-21T03:01:46.193 2: Autoguider: Alert
2018-11-21T03:01:46.219 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:01:49.280 2: Autoguider: Guiding
2018-11-21T03:02:19.879 2: Autoguider: Alert
2018-11-21T03:02:19.915 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:02:22.985 2: Autoguider: Guiding
2018-11-21T03:02:34.452 2: Autoguider: Alert
2018-11-21T03:02:34.507 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:02:35.237 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-10.0C_20181121_020234_11.fits
2018-11-21T03:02:35.372 1: Starting Light exposure 3/6 for 300 seconds
2018-11-21T03:02:36.627 2: Autoguider: Star lost
2018-11-21T03:02:36.661 1: Autoguider: StarLost Star lost - mass changed
2018-11-21T03:02:36.859 2: Autoguider: Guiding
2018-11-21T03:02:36.891 1: Autoguider: STAR_ERROR
2018-11-21T03:07:34.279 2: Autoguider: Alert
2018-11-21T03:07:34.309 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:07:45.366 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:07:45.985 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-9.8C_20181121_020745_11.fits
2018-11-21T03:07:46.086 1: Not autoguiding! dithering ignored.
2018-11-21T03:07:46.116 1: Starting Light exposure 4/6 for 300 seconds
2018-11-21T03:07:46.356 2: Autoguider: Guiding
2018-11-21T03:08:44.676 2: Autoguider: Alert
2018-11-21T03:08:44.706 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:08:47.712 2: Autoguider: Guiding
2018-11-21T03:09:04.393 2: Autoguider: Alert
2018-11-21T03:09:04.439 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:09:07.679 2: Autoguider: Guiding
2018-11-21T03:09:15.731 2: Autoguider: Alert
2018-11-21T03:09:15.769 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:09:18.916 2: Autoguider: Guiding
2018-11-21T03:12:56.843 2: Autoguider: Alert
2018-11-21T03:12:56.881 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:12:57.602 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-10.0C_20181121_021257_11.fits
2018-11-21T03:12:57.684 1: Starting Light exposure 5/6 for 300 seconds
2018-11-21T03:13:05.067 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:13:08.157 2: Autoguider: Guiding
2018-11-21T03:14:15.557 2: Autoguider: Alert
2018-11-21T03:14:15.592 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:14:18.522 2: Autoguider: Guiding
2018-11-21T03:18:08.418 2: Autoguider: Alert
2018-11-21T03:18:08.460 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:18:09.152 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-9.9C_20181121_021808_11.fits
2018-11-21T03:18:09.284 1: Starting Light exposure 6/6 for 300 seconds
2018-11-21T03:18:16.667 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:18:19.914 2: Autoguider: Guiding
2018-11-21T03:23:27.185 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018ipo\AT2018ipo_300s_1x1_-9.8C_20181121_022326_11.fits
2018-11-21T03:23:27.231 2: Stop capture
2018-11-21T03:23:27.535 1: Object AT2018ipo, plan 6x300s finished.
2018-11-21T03:23:28.548 1: Initialise target AT2018ipz
2018-11-21T03:23:28.582 2: Target AT2018ipz canceled before start, Stop time 18:26:00 already passed
2018-11-21T03:23:28.617 0: AT2018ipz, Target initialisation failed!
2018-11-21T03:23:28.677 1: Initialise target AT2018iqi
2018-11-21T03:23:28.713 3: Target can run until 08:42:11, in 19123 seconds
2018-11-21T03:23:28.756 2: Stop autoguider
2018-11-21T03:23:29.341 2: Autoguider: Lock Position Lost
2018-11-21T03:23:30.930 3: EQMOD.Telescope: Slewing to 07h31m57s/+22d41m00s
2018-11-21T03:24:17.616 3: EQMOD.Telescope: Slew is complete
2018-11-21T03:24:32.723 1: Object AT2018iqi, start plan 6x300s
2018-11-21T03:24:37.855 1: Start step 6x300s
2018-11-21T03:24:37.892 2: Start capture
2018-11-21T03:24:37.933 1: Autofocus now
2018-11-21T03:24:37.967 2: Get current position from current target
2018-11-21T03:24:38.064 2: Slew to focus star HIP 35930
2018-11-21T03:24:38.102 3: EQMOD.Telescope: Slewing to 07h24m24s/+22d55m36s
2018-11-21T03:24:47.253 3: EQMOD.Telescope: Slew is complete
2018-11-21T03:24:57.341 3: Take control exposure for 10.0 seconds
2018-11-21T03:25:17.378 3: Resolve control exposure
2018-11-21T03:25:17.931 3: Resolving using PlateSolve ...
2018-11-21T03:25:35.193 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T03:25:35.230 2: PlateSolve resolve successful. Solved in 17 seconds , offset 0.3090 degree
2018-11-21T03:25:35.300 3: Distance to target: 18.52311 arcmin
2018-11-21T03:25:35.361 3: EQMOD.Telescope: Sync to 07h23m07s/+22d50m12s
2018-11-21T03:25:37.577 3: EQMOD.Telescope: Slewing to 07h24m24s/+22d55m36s
2018-11-21T03:25:41.218 3: EQMOD.Telescope: Slew is complete
2018-11-21T03:25:51.311 3: Take control exposure for 10.0 seconds
2018-11-21T03:26:11.374 3: Resolve control exposure
2018-11-21T03:26:11.943 3: Resolving using PlateSolve ...
2018-11-21T03:26:29.249 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T03:26:29.284 2: PlateSolve resolve successful. Solved in 17 seconds , offset 0.0013 degree
2018-11-21T03:26:29.329 3: Distance to target: 0.17756 arcmin
2018-11-21T03:26:29.364 2: Precision slew finished.
2018-11-21T03:26:30.504 2: Focuser temperature: -1.7
2018-11-21T03:26:30.540 3: ASCOM.QHYCCD.Camera: Set binning 2x2
2018-11-21T03:26:30.687 2: Camera frame x=0 y=0 width=2772 height=1842
2018-11-21T03:26:32.720 3: Take control exposure for 5.0 seconds
2018-11-21T03:26:45.521 2: Camera frame x=1184 y=726 width=400 height=400
2018-11-21T03:26:45.557 2: Autofocus start Dynamic curve
2018-11-21T03:26:45.598 2: Focuser temperature: -1.7
2018-11-21T03:26:45.638 2: AutoFocus started, initial position: 20980
2018-11-21T03:26:54.169 3: Autofocus running, hfd=5.8 peak:60363.9 snr:227.0
2018-11-21T03:26:54.224 3: ASCOM.HomeMade.Focuser: Focuser move to 22087 + 5 Backlash compensation
2018-11-21T03:27:10.257 3: ASCOM.HomeMade.Focuser: Focuser move to 22087
2018-11-21T03:27:20.986 3: Autofocus mean frame 1/2, hfd=12.2 peak:19955.6 snr:114.6
2018-11-21T03:27:29.964 3: Autofocus mean frame 2/2, hfd=12.0 peak:19347.1 snr:112.2
2018-11-21T03:27:30.004 3: Autofocus running, hfd=12.1 peak:19347.1 snr:112.2
2018-11-21T03:27:30.051 3: ASCOM.HomeMade.Focuser: Focuser move to 21718
2018-11-21T03:27:44.314 3: Autofocus mean frame 1/2, hfd=8.8 peak:29826.0 snr:148.8
2018-11-21T03:27:53.297 3: Autofocus mean frame 2/2, hfd=8.9 peak:28793.8 snr:145.5
2018-11-21T03:27:53.338 3: Autofocus running, hfd=8.8 peak:28793.8 snr:145.5
2018-11-21T03:27:53.382 3: ASCOM.HomeMade.Focuser: Focuser move to 21349
2018-11-21T03:28:07.658 3: Autofocus mean frame 1/2, hfd=6.2 peak:60341.0 snr:226.9
2018-11-21T03:28:16.343 3: Autofocus mean frame 2/2, hfd=6.3 peak:60325.7 snr:226.8
2018-11-21T03:28:16.383 3: Autofocus running, hfd=6.2 peak:60325.7 snr:226.8
2018-11-21T03:28:16.429 3: ASCOM.HomeMade.Focuser: Focuser move to 20980
2018-11-21T03:28:30.612 3: Autofocus mean frame 1/2, hfd=6.1 peak:60355.4 snr:227.0
2018-11-21T03:28:39.205 3: Autofocus mean frame 2/2, hfd=5.9 peak:60332.7 snr:226.8
2018-11-21T03:28:39.274 3: Autofocus running, hfd=6.0 peak:60332.7 snr:226.8
2018-11-21T03:28:39.320 3: ASCOM.HomeMade.Focuser: Focuser move to 20611
2018-11-21T03:28:53.782 3: Autofocus mean frame 1/2, hfd=6.8 peak:60351.3 snr:226.9
2018-11-21T03:29:02.418 3: Autofocus mean frame 2/2, hfd=6.7 peak:60346.7 snr:226.9
2018-11-21T03:29:02.461 3: Autofocus running, hfd=6.8 peak:60346.7 snr:226.9
2018-11-21T03:29:02.506 3: ASCOM.HomeMade.Focuser: Focuser move to 20242
2018-11-21T03:29:17.302 3: Autofocus mean frame 1/2, hfd=8.8 peak:39083.2 snr:175.7
2018-11-21T03:29:25.853 3: Autofocus mean frame 2/2, hfd=9.1 peak:38889.6 snr:175.2
2018-11-21T03:29:25.907 3: Autofocus running, hfd=8.9 peak:38889.6 snr:175.2
2018-11-21T03:29:25.959 3: ASCOM.HomeMade.Focuser: Focuser move to 19873
2018-11-21T03:29:39.944 3: Autofocus mean frame 1/2, hfd=11.7 peak:20620.7 snr:117.2
2018-11-21T03:29:59.321 3: Autofocus mean frame 2/2, hfd=11.9 peak:18631.6 snr:109.4
2018-11-21T03:29:59.363 3: Autofocus running, hfd=11.8 peak:18631.6 snr:109.4
2018-11-21T03:29:59.439 3: HYPERBOLA curve fitting focus at 3.962, remaining curve fit error 0.0866, iteration cycles 4
2018-11-21T03:29:59.485 3: ASCOM.HomeMade.Focuser: Focuser move to 21363 + 5 Backlash compensation
2018-11-21T03:30:21.918 3: ASCOM.HomeMade.Focuser: Focuser move to 21363
2018-11-21T03:30:24.019 3: ASCOM.HomeMade.Focuser: Focuser move to 20994
2018-11-21T03:30:38.637 2: Autofocus finished, POS=20994 HFD=5.7 PEAK:60351.2 SNR:226.9 TEMP:-1.7
2018-11-21T03:30:38.683 3: ASCOM.QHYCCD.Camera: Set binning 1x1
2018-11-21T03:30:39.522 2: Camera frame x=2368 y=1452 width=800 height=800
2018-11-21T03:30:41.537 2: Camera frame x=0 y=0 width=5544 height=3684
2018-11-21T03:30:41.879 1: AutoFocus successful
2018-11-21T03:30:41.918 2: End preview
2018-11-21T03:30:41.956 2: Return to target position
2018-11-21T03:30:41.999 3: EQMOD.Telescope: Slewing to 07h30m49s/+22d43m28s
2018-11-21T03:30:50.340 3: EQMOD.Telescope: Slew is complete
2018-11-21T03:31:00.518 3: Take control exposure for 10.0 seconds
2018-11-21T03:31:21.682 3: Resolve control exposure
2018-11-21T03:31:22.234 3: Resolving using PlateSolve ...
2018-11-21T03:31:39.473 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T03:31:39.515 2: PlateSolve resolve successful. Solved in 17 seconds , offset 0.0318 degree
2018-11-21T03:31:39.576 3: Distance to target: 1.90218 arcmin
2018-11-21T03:31:39.615 2: Precision slew finished.
2018-11-21T03:31:39.662 2: Restart autoguider
2018-11-21T03:31:48.038 2: Autoguider: Looping Exposures
2018-11-21T03:31:54.334 2: Autoguider: Star Selected
2018-11-21T03:31:56.542 2: Autoguider: Star lost
2018-11-21T03:31:56.592 1: Autoguider: StarLost Star lost - low HFD
2018-11-21T03:31:56.632 1: Autoguider: StarLost Star lost - low HFD
2018-11-21T03:31:56.700 1: Autoguider: StarLost Star lost - low HFD
2018-11-21T03:31:58.322 2: Autoguider: Star Selected
2018-11-21T03:31:58.486 2: Autoguider: Looping Exposures
2018-11-21T03:32:00.844 2: Autoguider: Start Guiding
2018-11-21T03:32:02.866 2: Autoguider: Settling
2018-11-21T03:32:09.621 2: Autoguider: Guiding
2018-11-21T03:32:11.783 1: Starting Light exposure 1/6 for 300 seconds
2018-11-21T03:32:30.984 2: Autoguider: Alert
2018-11-21T03:32:31.028 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:32:33.818 2: Autoguider: Guiding
2018-11-21T03:37:21.853 2: Autoguider: Alert
2018-11-21T03:37:21.901 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:37:22.744 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-9.9C_20181121_023722_11.fits
2018-11-21T03:37:22.841 1: Starting Light exposure 2/6 for 300 seconds
2018-11-21T03:37:30.088 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:37:32.970 2: Autoguider: Guiding
2018-11-21T03:42:33.172 2: Autoguider: Alert
2018-11-21T03:42:33.221 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:42:33.902 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-9.8C_20181121_024233_11.fits
2018-11-21T03:42:33.980 1: Starting Light exposure 3/6 for 300 seconds
2018-11-21T03:42:36.143 2: Autoguider: Guiding
2018-11-21T03:47:04.560 2: Autoguider: Alert
2018-11-21T03:47:04.611 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:47:07.499 2: Autoguider: Guiding
2018-11-21T03:47:44.804 2: Autoguider: Alert
2018-11-21T03:47:44.859 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:47:45.848 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-10.0C_20181121_024745_11.fits
2018-11-21T03:47:45.940 1: Not autoguiding! dithering ignored.
2018-11-21T03:47:45.986 1: Starting Light exposure 4/6 for 300 seconds
2018-11-21T03:47:53.260 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:47:56.400 2: Autoguider: Guiding
2018-11-21T03:49:52.788 2: Autoguider: Alert
2018-11-21T03:49:52.835 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:49:55.864 2: Autoguider: Guiding
2018-11-21T03:52:27.908 2: Autoguider: Alert
2018-11-21T03:52:27.956 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:52:30.976 2: Autoguider: Guiding
2018-11-21T03:52:56.593 2: Autoguider: Alert
2018-11-21T03:52:56.643 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:52:57.343 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-10.0C_20181121_025256_11.fits
2018-11-21T03:52:57.499 1: Starting Light exposure 5/6 for 300 seconds
2018-11-21T03:53:04.844 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:53:07.899 2: Autoguider: Guiding
2018-11-21T03:54:59.909 2: Autoguider: Alert
2018-11-21T03:54:59.960 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:55:02.766 2: Autoguider: Guiding
2018-11-21T03:58:07.590 2: Autoguider: Alert
2018-11-21T03:58:07.643 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T03:58:08.343 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-9.9C_20181121_025807_11.fits
2018-11-21T03:58:08.484 1: Starting Light exposure 6/6 for 300 seconds
2018-11-21T03:58:10.199 2: Autoguider: Guiding
2018-11-21T04:03:18.789 2: Autoguider: Alert
2018-11-21T04:03:18.843 1: Autoguider: Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure, so it has been disconnected to prevent other problems. If you think the hardware is working correctly, you can increase the timeout period on the Camera tab of the Advanced Settings Dialog.
PHD will make several attempts to re-connect the camera.
2018-11-21T04:03:19.542 1: Saved file C:\APT_Images\CameraCCD_1\Light\AT2018iqi\AT2018iqi_300s_1x1_-9.9C_20181121_030318_11.fits
2018-11-21T04:03:19.594 2: Stop capture
2018-11-21T04:03:19.977 1: Object AT2018iqi, plan 6x300s finished.
2018-11-21T04:03:20.769 1: Initialise target AT2018iqm
2018-11-21T04:03:20.821 3: Target can run until 08:37:36, in 16455 seconds
2018-11-21T04:03:20.874 2: Stop autoguider
2018-11-21T04:03:21.042 2: Autoguider: Lock Position Lost
2018-11-21T04:03:23.257 3: EQMOD.Telescope: Slewing to 07h39m23s/+19d34m00s
2018-11-21T04:03:33.324 3: EQMOD.Telescope: Slew is complete
2018-11-21T04:03:48.444 1: Object AT2018iqm, start plan 6x300s
2018-11-21T04:03:53.520 1: Start step 6x300s
2018-11-21T04:03:53.578 2: Start capture
2018-11-21T04:03:53.637 1: Autofocus now
2018-11-21T04:03:53.689 2: Get current position from current target
2018-11-21T04:03:53.762 2: Slew to focus star HIP 37054
2018-11-21T04:03:53.847 3: EQMOD.Telescope: Slewing to 07h36m54s/+20d29m12s
2018-11-21T04:03:59.953 3: EQMOD.Telescope: Slew is complete
2018-11-21T04:04:10.032 3: Take control exposure for 10.0 seconds
2018-11-21T04:04:30.238 3: Resolve control exposure
2018-11-21T04:04:30.820 3: Resolving using PlateSolve ...
2018-11-21T04:04:48.251 2: Open file C:\Users\cedric\AppData\Local\ccdciel\tmp\ccdcielsolved.fits
2018-11-21T04:04:48.304 2: PlateSolve resolve successful. Solved in 17 seconds , offset 0.0122 degree
2018-11-21T04:04:48.392 3: Distance to target: 0.74620 arcmin
2018-11-21T04:04:48.444 2: Precision slew finished.
2018-11-21T04:04:49.524 2: Focuser temperature: -2.0
2018-11-21T04:04:49.597 3: ASCOM.QHYCCD.Camera: Set binning 2x2
2018-11-21T04:04:49.762 2: Camera frame x=0 y=0 width=2772 height=1842
2018-11-21T04:04:51.802 3: Take control exposure for 5.0 seconds
2018-11-21T04:05:03.647 2: Camera frame x=1228 y=726 width=400 height=400
2018-11-21T04:05:04.490 2: Autofocus start Dynamic curve
2018-11-21T04:05:04.548 2: Focuser temperature: -2.0
2018-11-21T04:05:04.601 2: AutoFocus started, initial position: 20994
2018-11-21T04:05:23.283 3: Autofocus running, hfd=5.6 peak:60364.3 snr:227.0
2018-11-21T04:05:23.345 3: ASCOM.HomeMade.Focuser: Focuser move to 22101 + 5 Backlash compensation
2018-11-21T04:05:41.815 3: ASCOM.HomeMade.Focuser: Focuser move to 22101
2018-11-21T04:05:52.018 3: Autofocus mean frame 1/2, hfd=12.3 peak:16413.2 snr:100.4
2018-11-21T04:06:00.663 3: Autofocus mean frame 2/2, hfd=12.3 peak:16275.2 snr:99.8
2018-11-21T04:06:00.719 3: Autofocus running, hfd=12.3 peak:16275.2 snr:99.8
2018-11-21T04:06:00.780 3: ASCOM.HomeMade.Focuser: Focuser move to 21732
2018-11-21T04:06:15.497 3: Autofocus mean frame 1/2, hfd=8.9 peak:24411.6 snr:131.0
2018-11-21T04:06:34.355 3: Autofocus mean frame 2/2, hfd=8.9 peak:23429.5 snr:127.6
2018-11-21T04:06:34.433 3: Autofocus running, hfd=8.9 peak:23429.5 snr:127.6
2018-11-21T04:06:34.494 3: ASCOM.HomeMade.Focuser: Focuser move to 21363
2018-11-21T04:06:48.746 3: Autofocus mean frame 1/2, hfd=6.2 peak:45735.8 snr:193.3
2018-11-21T04:07:07.671 3: Autofocus mean frame 2/2, hfd=6.6 peak:38929.9 snr:175.5
2018-11-21T04:07:07.728 3: Autofocus running, hfd=6.4 peak:38929.9 snr:175.5
2018-11-21T04:07:07.789 3: ASCOM.HomeMade.Focuser: Focuser move to 20994
2018-11-21T04:07:22.410 3: Autofocus mean frame 1/2, hfd=5.6 peak:60402.3 snr:227.2
2018-11-21T04:07:41.379 3: Autofocus mean frame 2/2, hfd=5.6 peak:60379.5 snr:227.1
2018-11-21T04:07:41.452 3: Autofocus running, hfd=5.6 peak:60379.5 snr:227.1
2018-11-21T04:07:41.514 3: ASCOM.HomeMade.Focuser: Focuser move to 20625
2018-11-21T04:07:55.680 3: Autofocus mean frame 1/2, hfd=6.3 peak:60411.4 snr:227.3
2018-11-21T04:08:04.523 3: Autofocus mean frame 2/2, hfd=6.2 peak:60369.9 snr:227.0
2018-11-21T04:08:04.581 3: Autofocus running, hfd=6.3 peak:60369.9 snr:227.0
2018-11-21T04:08:04.645 3: ASCOM.HomeMade.Focuser: Focuser move to 20256
2018-11-21T04:08:18.748 3: Autofocus mean frame 1/2, hfd=8.5 peak:39235.2 snr:176.3
2018-11-21T04:08:27.422 3: Autofocus mean frame 2/2, hfd=8.3 peak:35137.1 snr:164.8
2018-11-21T04:08:27.482 3: Autofocus running, hfd=8.4 peak:35137.1 snr:164.8
2018-11-21T04:08:27.565 3: ASCOM.HomeMade.Focuser: Focuser move to 19887

I doubt the memory was actually exhausted given that in normal operation there's 3GB free.

Patrick Chevalley

18-11-21 12:19

administrator   ~0005128

OK, will search for other problem. Maybe the phd2 interface as it is the only function you use that use a socket.

A specific point in your log is all this phd2 alert about guide camera stall: "Alert warning, After 8.0 sec the camera has not completed a 2.0 sec exposure,"
Do you have an idea of what go wrong? this usually indicate problem with the usb connection to the camera.
I have to look if this specific message is handled correctly in ccdciel.

Also at the start of this session it look like the Eqmod unpark do not work correctly, ccdciel send a unpark command at 02:40:33 but the first slew at 02:41:28 say it is parked. Any idea why?

Cedric Raguenaud

18-11-21 12:41

reporter   ~0005129

The guide camera errors have always been there. I've replaced usb cables, usb hub, usb card in the computer, nothing made a difference. It's not a major issue, it just means that once in a while the mount is left to track unguided for 8-10 seconds, which isn't a problem (I've done up to 5 minutes once).

I can't explain the failed unpark, I don't remember. I was half asleep at that point.

Patrick Chevalley

18-11-21 15:10

administrator   ~0005130

I make a few change in the PHD2 interface to protect again corrupt JSON message and infinite recursion in message parsing, even I not found a case that can raise this problem.
https://github.com/pchev/ccdciel/commit/b3ba6948db018f8cde07a4ada6612eb9f3259720

Another strange point is the LockPositionLost event send every time we try to stop guiding before to slew. Normally we must receive LoopingExposuresStopped.

Just to be sure, what is your version of PHD2?

Cedric Raguenaud

18-11-21 15:14

reporter   ~0005131

My version of Phd2 is currently 2.6.5dev6.

Would it be possible to catch any exception coming from PhD communication just in case?

Patrick Chevalley

18-11-21 16:13

administrator   ~0005132

This is the last version, good.

With today change everything is trapped, I try with voluntarily altered messages, now it show an error message and continue.
If something is wrong with the socket it close the connection.

I run a test since yesterday in a Windows virtual machine, with cdc, and firefox connected, and phd2 connected and guiding. I start with a preview loop during 12 hours, then a 6 hours sequence that take 1000 pictures of different targets. No problem at all.

I also have no report of other user with this issue so I think we must search something specific to your setup.

Maybe you can create a new profile with only the ascom simulators, phd2 using it's internal simulator. Run one of your sequence, if it work this eliminates the computer itself as a source of problem (bad memory can produce this kind of symptom, can you also run a memtest?).

Then replace progressively an emulated piece by a real one, the main camera is the more easy, run the test again. Continue by adding the real mount, the real guide camera and look when it start to fail.

Patrick Chevalley

18-11-30 10:20

administrator   ~0005139

I keep only one issue open for this problem

Issue History

Date Modified Username Field Change
18-11-20 14:51 Cedric Raguenaud New Issue
18-11-20 14:51 Cedric Raguenaud File Added: Screenshot_2018-11-19-22-25-56.png
18-11-20 16:53 Patrick Chevalley Assigned To => Patrick Chevalley
18-11-20 16:53 Patrick Chevalley Status new => feedback
18-11-20 16:53 Patrick Chevalley Target Version => 1.0
18-11-20 16:53 Patrick Chevalley Description Updated View Revisions
18-11-20 16:53 Patrick Chevalley Note Added: 0005113
18-11-20 23:04 Patrick Chevalley File Added: test_ascom_camera.zip
18-11-20 23:04 Patrick Chevalley File Added: test_camera.png
18-11-20 23:04 Patrick Chevalley Note Added: 0005123
18-11-21 09:13 Cedric Raguenaud Note Added: 0005124
18-11-21 09:13 Cedric Raguenaud Status feedback => assigned
18-11-21 10:08 Patrick Chevalley Note Added: 0005125
18-11-21 10:44 Cedric Raguenaud Note Added: 0005126
18-11-21 10:48 Cedric Raguenaud Note Added: 0005127
18-11-21 12:19 Patrick Chevalley Status assigned => feedback
18-11-21 12:19 Patrick Chevalley Note Added: 0005128
18-11-21 12:41 Cedric Raguenaud Note Added: 0005129
18-11-21 12:41 Cedric Raguenaud Status feedback => assigned
18-11-21 15:10 Patrick Chevalley Status assigned => feedback
18-11-21 15:10 Patrick Chevalley Note Added: 0005130
18-11-21 15:14 Cedric Raguenaud Note Added: 0005131
18-11-21 15:14 Cedric Raguenaud Status feedback => assigned
18-11-21 16:12 Patrick Chevalley Status assigned => feedback
18-11-21 16:12 Patrick Chevalley Additional Information Updated View Revisions
18-11-21 16:13 Patrick Chevalley Additional Information Updated View Revisions
18-11-21 16:13 Patrick Chevalley Note Added: 0005132
18-11-30 10:20 Patrick Chevalley Status feedback => closed
18-11-30 10:20 Patrick Chevalley Resolution open => duplicate
18-11-30 10:20 Patrick Chevalley Relationship added duplicate of 0002033
18-11-30 10:20 Patrick Chevalley Note Added: 0005139