AstroHuntsman / huntsman-pocs

POCS on the Huntsman Telescope
MIT License
7 stars 8 forks source link

Exposure failed crashes autofocus #452

Open danjampro opened 3 years ago

danjampro commented 3 years ago

An exposure failed during autofocus results in hang until timeout, then state machine goes to parking.

danjampro commented 3 years ago

CC logs:

E 06-07 02:49:04.550 (02:49:04 UTC) | panoptes.utils.error __init__:13                                | Timeout: Timeout waiting for 5 events after 900.02 seconds
C 06-07 02:49:04.555 (02:49:04 UTC) | panoptes.pocs.state.machine run:141                             | Problem going from 'observing' to 'scheduling', exiting loop [Timeout('Timeout waiting for 5 events after 900.02 seconds')]

pi logs:

D 06-07 02:36:58.153 (02:36:58 UTC) | panoptes.pocs.camera.camera take_exposure:498                        | Taking seconds=<Quantity 0.00219 s> exposure on 192.168.80.145: filename='images/focus/1919420013090900/20210607T023359/22646-06.fits'
D 06-07 02:37:03.204 (02:37:03 UTC) | panoptes.pocs.camera.libasi set_control_value:237                    | Set EXPOSURE to 0.00219 s on camera 0
D 06-07 02:37:03.210 (02:37:03 UTC) | panoptes.pocs.camera.libasi start_exposure:451                       | Exposure started on camera 0
D 06-07 02:37:03.214 (02:37:03 UTC) | panoptes.pocs.camera.camera _poll_exposure:811                       | Polling exposure with timeout of 60.10219 seconds.
D 06-07 02:37:03.217 (02:37:03 UTC) | panoptes.pocs.camera.camera take_exposure:531                        | Blocking on exposure event for 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel
D 06-07 02:37:03.220 (02:37:03 UTC) | panoptes.utils.time restart:164                                      | Restarting Timer  60.10/60.10
W 06-07 02:37:07.436 (02:37:07 UTC) | huntsman.pocs.camera.zwo _readout:344                                | Exposure failed on 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel. Reconnecting camera.
D 06-07 02:37:08.182 (02:37:08 UTC) | panoptes.pocs.camera.libasi close_camera:158                         | Closed camera 0
W 06-07 02:37:08.185 (02:37:08 UTC) | huntsman.pocs.camera.zwo _reset_usb:404                              | Resetting USB for 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel.
D 06-07 02:37:08.190 (02:37:08 UTC) | panoptes.pocs.camera.libasi get_product_ids:118                      | Got 93 supported camera product IDs from SDK.
D 06-07 02:37:08.480 (02:37:08 UTC) | huntsman.pocs.camera.zwo _reset_usb:408                              | Identified USB product ID: 6206.
D 06-07 02:37:08.769 (02:37:08 UTC) | huntsman.pocs.camera.zwo connect:154                                 | Connecting to 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel
D 06-07 02:37:08.814 (02:37:08 UTC) | panoptes.pocs.camera.libasi get_camera_property:131                  | Got info from camera 0, ZWO ASI183MM Pro
D 06-07 02:37:08.856 (02:37:08 UTC) | panoptes.pocs.camera.libasi open_camera:148                          | Opened camera 0
D 06-07 02:37:09.449 (02:37:09 UTC) | panoptes.pocs.camera.libasi init_camera:153                          | Initialised camera 0
D 06-07 02:37:09.455 (02:37:09 UTC) | panoptes.pocs.camera.libasi get_num_of_controls:194                  | Camera 0 has 14 controls
D 06-07 02:37:09.467 (02:37:09 UTC) | panoptes.pocs.camera.libasi get_control_caps:209                     | Got details of 14 controls from camera 0
D 06-07 02:37:09.470 (02:37:09 UTC) | panoptes.pocs.camera.libasi disable_dark_subtract:330                | Dark subtraction on camera 0 disabled.
D 06-07 02:37:09.475 (02:37:09 UTC) | panoptes.pocs.camera.libasi set_control_value:237                    | Set GAIN to 100 on camera 0
D 06-07 02:37:09.481 (02:37:09 UTC) | panoptes.pocs.camera.libasi get_camera_property:131                  | Got info from camera 0, ZWO ASI183MM Pro
D 06-07 02:37:09.537 (02:37:09 UTC) | panoptes.pocs.camera.libasi set_roi_format:270                       | Set ROI, format on camera 0 to 5496x3672/1, RAW16
D 06-07 02:37:09.542 (02:37:09 UTC) | panoptes.pocs.camera.camera cooling_enabled:240                      | Setting 192.168.80.145 cooling enabled to True
D 06-07 02:37:09.548 (02:37:09 UTC) | panoptes.pocs.camera.libasi set_control_value:237                    | Set COOLER_ON to True on camera 0
E 06-07 02:37:09.552 (02:37:09 UTC) | panoptes.utils.error __init__:13                                     | PanError: Exposure failed on 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel
E 06-07 02:37:09.558 (02:37:09 UTC) | panoptes.pocs.camera.camera _poll_exposure:830                       | Error during readout on 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel: PanError('Exposure failed on 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel')
E 06-07 02:37:09.564 (02:37:09 UTC) | panoptes.pocs.camera.camera log_thread_error:520                     | _thread.ExceptHookArgs(exc_type=<class 'panoptes.utils.error.PanError'>, exc_value=PanError('Exposure failed on 192.168.80.145 (1919420013090900) with Birger Focuser & ZWO Filter Wheel with ZWO Filter Wheel'), exc_traceback=<traceback object at 0xffff701f0900>, thread=<Thread(Thread-71, started daemon 281472334283232)>)