David's Astronomy Pages
Notes - Session 863 (2021-02-25)

 
Bullet Session Aims & Highlights
 - Observing Result
 - Night Summary Plot
 - Session Event Log
 
Bullet Operational Issues
  - Critical Issues (0),  Major Issues (2),  Minor Issues (4),  Continuous Improvement (3)
Bullet Images from 2021-02-25 >>     [ Local Files >> ]
2021-02-26
Bullet Investigation - UI lock up during live sessions
Bullet Pulsar Dome - Software Update (Rotation 1.41)
 

Session Aims & Highlights (2021-02-25)

Main aims

  1. AstroMain.  Test features and fixes added in AstroMain 3.37/3.37.1 software, and crucially test stability and absence of UI lockups
  2. ObsCam. Conduct thrid live test of new AstroObsCam software for capturing webcam pictures of the telescope & interior of the observatory during operation including AstroAllSky-AstroObsCam communication and picture upload & display on current session webpage.
  3. Targets. Acquire images of a selection of variable stars, nearby stars, comets & deep sky targets as allowed by conditions.

Equipment & Software

Highlights

Summary Plots & Logs

Observing Plan
Image
  
Observing Result
Image
   
Dome & Scope Slewing Performance
Image
  
Slew/Centering Performance
Image
  
Guiding Performance
Image
Image
  
Sky Conditions (Locate Frames)
Image
  
Night Sky Summary Plot
Top axis: Sky Brightness at Zenith (in ADU/s)
Lefthand axis: Local Time (hh LT). Righthand axis: Sun Altitude (degs)
Image   
  
Pre-Session Weather Forecast (API data from darksky.net)
Image
Image   
  
Session Event Log
Time     Event Detail
18:48:48 Session Created Live Session Created (2021-02-25 S00863, ImageSaveNum: 863001)
18:48:50   Scope Switched On Telescope Power has been switched on via UPB Powerbox.
18:50:12   Services Started Observatory Services started
18:57:00 Program Crashed Program crash due to fatal exception relared to an introduced bug
19:18:27 Session Monitoring AutoStart monitoring for Live Session opportunity starting between 19:18 & 05:15
19:18:30 Session AutoStarting Session autostarting (19:18)
19:18:59 Session Resumed Live Session Resumed (2021-02-25 S00863, ImageSaveNum: 863001)
19:19:01   Scope Switched On Telescope Power has been switched on via UPB Powerbox.
19:20:45   Services Started Observatory Services started
19:20:54 Observatory (Auto) Observatory placed in Fully-Automated Mode
19:20:55 Session Pending Session pending (2021-02-25)
19:20:57 Session Initiating Session initiating (2021-02-25)
19:20:59   Plan Requested Observing Plan requested from AstroPlan (1.20.5)
19:22:38   Plan Loaded Observing Plan loaded to queue (, Plan ID: 600)
19:22:50 Program Resumed AstroMain UI resumed again at 19:22 after 7s
19:22:52   Camera1 Connected SBIG Camera Connected (set point -20°C)
19:22:58   Telescope Connected Telescope Connected (TheSky6)
19:23:09 Session Equilibration Session ready for dome & camera equilibration
19:23:55   Dome Opened Dome opened (opening time 46s)
19:23:57 Session Running Session running
19:23:59   Queue Started Observing Queue started (38 targets selected)
19:24:56       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
19:27:55       Focusing Completed Foc1 AutoFocus Completed (Profile No 1)
19:29:41       Focusing Completed Foc1 AutoFocus Completed (Profile No 1)
19:29:45       Focusing Started-Foc2 Foc2 Focusing Started (Secondary Scope, using ShCap)
19:32:06       Focusing Completed Foc2 AutoFocus Completed (Profile No 2, wide)
19:34:00       Focusing Completed Foc2 AutoFocus Completed (Profile No 2)
19:34:16       Focusing Checked Foc2 Focusing Check Succeeded
19:35:55     Target Started (1/38) Target started (1/38, UGC 2505 w/SN2021bge)
19:38:18 Program Hung AstroMain UI appears to have stopped responding at 19:38
19:47:32 Session Resumed Live Session Resumed (2021-02-25 S00863, ImageSaveNum: 863004)
19:48:02   Obs.Manager Started Obs.Manager started
19:48:04   Obs.Overseer Started Obs.Overseer started
19:48:08   Services Started Observatory Services started
19:48:12   Scope Switched On Telescope Power has been switched on via UPB Powerbox.
19:49:56   Services Started Observatory Services started
19:51:31 Observatory (Auto) Observatory placed in Fully-Automated Mode
19:51:32 Session Pending Session pending (2021-02-25)
19:51:34 Session Initiating Session initiating (2021-02-25)
19:51:37   Plan Loaded Observing Plan loaded to queue (, Plan ID: 600)
19:51:40   Camera1 Connected SBIG Camera Connected (set point -20°C)
19:51:49   Telescope Connected Telescope Connected (TheSky6)
19:52:02 Session Equilibration Session ready for dome & camera equilibration
19:52:04 Session Running Session running
19:52:06   Queue Started Observing Queue started (37 targets selected)
19:55:02 Program Hung AstroMain UI appears to have stopped responding at 19:55
20:02:13 Session Resumed Live Session Resumed (2021-02-25 S00863, ImageSaveNum: 863004)
20:02:29   Obs.Manager Started Obs.Manager started
20:02:31   Obs.Overseer Started Obs.Overseer started
20:02:34   Services Started Observatory Services started
20:02:41   Scope Switched On Telescope Power has been switched on via UPB Powerbox.
20:04:26   Services Started Observatory Services started
20:04:56 Observatory (Auto) Observatory placed in Fully-Automated Mode
20:04:59 Session Pending Session pending (2021-02-25)
20:05:01 Session Initiating Session initiating (2021-02-25)
20:05:03   Plan Loaded Observing Plan loaded to queue (, Plan ID: 600)
20:05:07   Camera1 Connected SBIG Camera Connected (set point -20°C)
20:05:16   Telescope Connected Telescope Connected (TheSky6)
20:05:29 Session Equilibration Session ready for dome & camera equilibration
20:05:31 Session Running Session running
20:05:33   Queue Started Observing Queue started (37 targets selected)
20:05:35     Target Started (2/38) Target started (2/38, Nova Per 2020 (CV))
20:16:06     Target Completed Target partially completed (2/38, Nova Per 2020 (CV))
20:16:08     Target Started (3/38) Target started (3/38, AT2021ckv)
20:19:53       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
20:21:55       Focusing Completed Foc1 AutoFocus Completed (Profile No 5)
20:38:02     Target Completed Target completed (3/38, AT2021ckv)
20:38:05     Target Started (4/38) Target started (4/38, UGC 1436 w/SN2021nv)
20:41:58       Focusing Skipped Foc1 focusing skipped - star is too dim (TCF-S)
20:59:17     Target Completed Target completed (4/38, UGC 1436 w/SN2021nv)
20:59:19     Target Started (5/38) Target started (5/38, AT2021dbp (CV))
21:03:07       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
21:05:09       Focusing Completed Foc1 AutoFocus Completed (Profile No 6)
21:10:56     Target Completed Target completed (5/38, AT2021dbp (CV))
21:10:59     Target Started (6/38) Target started (6/38, GCVS RR Tau)
21:13:47       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
21:15:54       Focusing Completed Foc1 AutoFocus Completed (Profile No 7)
21:21:58     Target Completed Target completed (6/38, GCVS RR Tau)
21:22:00     Target Started (7/38) Target started (7/38, NGC 304 w/SN2021dnn)
21:25:45       Focusing Skipped Foc1 focusing skipped - star is too dim (TCF-S)
21:43:20     Target Completed Target completed (7/38, NGC 304 w/SN2021dnn)
21:43:22     Target Started (8/38) Target started (8/38, GCVS RX And)
21:47:21       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
21:49:19       Focusing Completed Foc1 AutoFocus Completed (Profile No 8)
21:51:59     Target Completed Target completed (8/38, GCVS RX And)
21:52:01     Target Started (9/38) Target started (9/38, C/2019 L3 (ATLAS))
21:56:12       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
21:59:01       Focusing Completed Foc1 AutoFocus Completed (Profile No 9)
22:05:51     Target Completed Target completed (9/38, C/2019 L3 (ATLAS))
22:05:53     Target Started (10/38) Target started (10/38, SN 2021xn)
22:11:06       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
22:13:19       Focusing Completed Foc1 AutoFocus Completed (Profile No 10)
22:28:57     Target Completed Target completed (10/38, SN 2021xn)
22:28:59     Target Started (11/38) Target started (11/38, GCVS DY Per)
22:31:39       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
22:33:29       Focusing Completed Foc1 AutoFocus Completed (Profile No 11)
22:37:57     Target Completed Target completed (11/38, GCVS DY Per)
22:37:59     Target Started (12/38) Target started (12/38, 398P/Boattini)
22:41:40       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
22:43:43       Focusing Failed Foc1 focusing failed - failed QC check (Ambiguous)
22:52:13     Target Completed Target completed (12/38, 398P/Boattini)
22:52:15     Target Started (13/38) Target started (13/38, Scholz's Star)
22:55:40       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
22:57:53       Focusing Completed Foc1 AutoFocus Completed (Profile No 13)
23:13:36     Target Completed Target completed (13/38, Scholz's Star)
23:13:38     Target Started (14/38) Target started (14/38, GCVS DX Cnc)
23:17:40       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
23:20:06       Focusing Completed Foc1 AutoFocus Completed (Profile No 14)
23:25:26     Target Completed Target completed (14/38, GCVS DX Cnc)
23:25:28     Target Started (15/38) Target started (15/38, CGCG 264-017 w/AT2021bjy)
23:29:11       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
23:31:14       Focusing Completed Foc1 AutoFocus Completed (Profile No 15)
23:47:18     Target Completed Target completed (15/38, CGCG 264-017 w/AT2021bjy)
23:47:20     Target Started (16/38) Target started (16/38, LAMOST-HVS1)
23:51:13       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
23:53:15       Focusing Completed Foc1 AutoFocus Completed (Profile No 16)
23:57:05     Target Completed Target completed (16/38, LAMOST-HVS1)
23:57:07     Target Started (17/38) Target started (17/38, UGC 3502 w/AT2021cwc)
00:00:35       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
00:02:44       Focusing Completed Foc1 AutoFocus Completed (Profile No 17)
00:18:28     Target Completed Target completed (17/38, UGC 3502 w/AT2021cwc)
00:18:30     Target Started (18/38) Target started (18/38, GCVS U Gem)
00:22:47       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
00:24:50       Focusing Completed Foc1 AutoFocus Completed (Profile No 18)
00:27:50     Target Completed Target completed (18/38, GCVS U Gem)
00:27:52     Target Started (19/38) Target started (19/38, GCVS RZ LMi)
00:31:53       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
00:33:43       Focusing Completed Foc1 AutoFocus Completed (Profile No 19)
00:38:50     Target Completed Target completed (19/38, GCVS RZ LMi)
00:43:46     Target Started (20/38) Target started (20/38, AT2021rl)
01:01:06     Target Completed Target partially completed (20/38, AT2021rl)
01:01:08     Target Started (21/38) Target started (21/38, NGC 4041 w/AT2021cek)
01:05:02       Focusing Started-Foc1 Foc1 Focusing Started (TCF-S)
01:07:05       Focusing Completed Foc1 AutoFocus Completed (Profile No 20)
01:19:08   SoftSuspend Called Soft Suspend is called due to Deteriorating Conditions (too few stars)
01:20:19   SoftSuspend Cancelled Soft Suspend is cancelled due to Improved Conditions
01:22:54   SoftSuspend Called Soft Suspend is called due to Deteriorating Conditions (too few stars)
01:23:12     Target Completed Target completed (21/38, NGC 4041 w/AT2021cek)
01:23:14   Queue Paused Queue paused for Session Suspension
01:23:16 Session Suspended Session suspended
01:24:04   Dome Closed Dome closed (closing time 50s)
01:39:32     Target Cancelled Target cancelled (22/38, NGC 4227 w/SN2021biz) due to cloud
02:01:24     Target Cancelled Target cancelled (23/38, NGC 4533 w/SN2021bug) due to cloud
02:13:42     Target Cancelled Target cancelled (24/38, GCVS ER UMa) due to cloud
02:30:04     Target Cancelled Target cancelled (25/38, UGC 6930 w/SN2020rcq) due to cloud
02:47:26     Target Cancelled Target cancelled (26/38, KUG 1206+328 w/SN2020aeps) due to cloud
03:08:18     Target Cancelled Target cancelled (27/38, NGC 4631 w/AT2021biy) due to cloud
03:30:10     Target Cancelled Target cancelled (28/38, NGC 6223 w/SN2021bls) due to cloud
03:42:28     Target Cancelled Target cancelled (29/38, GCVS BL Lac) due to cloud
04:02:20     Target Cancelled Target cancelled (30/38, AT2021dtd) due to cloud
04:24:12     Target Cancelled Target cancelled (31/38, NGC 4774 w/AT2021cjd) due to cloud
04:35:38     Target Cancelled Target cancelled (32/38, GCVS R CrB) due to cloud
04:46:33     Target Cancelled Target cancelled (33/38, GCVS U Her) due to cloud
04:56:06     Target Cancelled Target cancelled (34/38, GCVS AM CVn) due to cloud
05:15:03     Target Cancelled Target cancelled (35/38, C/2020 T2 (Palomar)) due to cloud
05:24:24     Target Cancelled Target cancelled (36/38, GCVS SS Her) due to cloud
05:32:34     Target Cancelled Target cancelled (37/38, HIP 87937) due to cloud
05:51:54     Target Cancelled Target cancelled (38/38, GCVS CY Lyr) due to other condition
05:51:59   Queue Completed Job Queue completed
05:52:03 Session Closing Session closing
05:52:08   Dome Closed Dome already closed (closed at 01:24, closing time 50s)
05:52:49   Dome Parked Dome parked (parking time 32s), Az: 90.0 deg
05:53:37   Telescope Parked Telescope parked (parking time 40s)
05:54:07   Telescope Switched Off Telescope Power has been switched off via UPB Switch.
05:55:01   Services Stopped Night Services stopped
05:55:03 Session Housekeeping Housekeeping Started (Cleanup FITS, Create Fits Summary, Transfer Files)
05:55:51 Session Finished Session Finished
 
 
Session Alerts
Time     Alert Detail
22:07:21 Dome Dome position has been static for more than 5 mins
22:09:26 Dome Dome has been stationary for more than 10 mins
22:14:31 Dome Dome Movements have begun again (outage 15.1 mins)
23:26:49 Dome Discrepency Difference between Telescope Az and Dome Az is > 8 deg (CGCG 264-017)
00:08:02 Dome Dome has been stationary for more than 10 mins
00:15:53 Dome Dome Movements have begun again (outage 17.9 mins)
01:02:44 Dome Discrepency Difference between Telescope Az and Dome Az is > 8 deg (NGC 4041)
 
 

Back to Top


Operational Issues (2021-02-25 S863)

[ Prev | Next ]

Critical Issues

Major Issues

Minor Issues

Continuous Improvement

[ Prev | Next ]

Back to Top


2021-02-26


Investigation - UI lock up during live sessions

Issue:  UI locks up when the user click on the program form whilst an automated session is active.

Description
This has been a long standing issue, and whilst it had largely disappeared for session between x and x, the issue has reappeared again during recent sessions. It means that it that there is a regular requirement to park the telescope, kill the program and restart observatory software and the session to continue the session.  The issue is being managed by setting the program to automated mode and to then leave the program and indeed the whole machine totally alone in order to not interact with the program and to thereby not cause the issue to occur.  This however means that user can't directly check upon chart displays and data in the program or to interact with the running program by change various settings.     Chart Displays and dat can fortunately be checked by examining displays and data uploaded from the program to the Current Session WebPage or by opening picture files and document files (graphs, log files etc) output to various folders on the observatory computer.

When it works the UI is a pleasure to use (having been adpated and streamlined over several years of use and fine tuning),  but has because the program's achilles heel, something that one is scared to use for a real fear of causing it to lock up with consequent impact to the smooth running of the session

Historically the UI lockups are associated with stalled / frozen programs threads, typically Obs.Monitor and Obs.Worker, but sometime also extending to Job Queue and Obs.Manager.   A separate thread (Obs.Overseer)  was added to the program a while ago with the sole task of keeping tabs on what threads are working and which are frozen/stalled, with the capability to make the observatory safe after a certain amount of time has passed.

S863

In S863 (v3.37.2) the AstroProgram UI hung shortly after restarting Live Session (19:38 & 19:55).  Two similar indicidents. Scope had to be parked , the AstroMain program killed and suite of observatory software & session restarted. Program hang happened when the main program form was clicked upon to bring it to the front. Opportunity was taken on the second occasion to install a revised version of the program(3.37.3.1) to fix an issue where the log file was recording UNIQUE constraint failed exceptions from multiple attempts to retrospectively Insert SessionEvents for 'Program Hung  - AstroMain UI appears to have stopped responding..."

In first case UI hung up at 19:38:46 and remained in a non-responsive state till 19:46:23 when the program was killed (a period of some484s)   The Obs.Manager froze at 19:44:36 its unclear what caused it to freeze at this time, logfile provides no indication.

Throughout the period there exceptions every 5s reporting UNIQUE constraint failure by trying to Insert Session Event recording the Program was hung. This was caused by a bug (fixed in 3.37.3) in recently modified Obs.Overseer code and was exposed as a consequence of the underlying issue but  was not the cause of the UI lockup.

Throughout the period ObsWorker reported every 5s that its calls to UpdateSynopsisFields were taking > 1s. (this means that ObsWorker stopped waiting after 1s  for the BeginInvoke to be completed ). There is a high probability that the UpdateSynopsis fields delegation weren't being completed and there was a gradual build up of tasks for UI

Last message from the Job Queue was 19:38:45.32  (Convert FitsToJPG Ok | Converting Image 863003 to JPG), but there were already 'UpdateSynopsisFields has taken > 1s' message going back to 19:38:43,  19:38:37, 19:38:33, 19:38:31, 19:38:27, 19:38:23, 19:22:47 (* two messages lines produced at this time)

Throughout the period ObsMonitor/ObsViewer continued running as evidenced by uninterrupted series of ObsPics, every 30s.

Analysis
The UI is presumed to lock up because it becomes too overloaded with taks that it is unable to respond to the user and windows steps in to flag that the program is 'Not Responding' or the program UI becomes involved in an infinite loop or some form of blocking loop.

Actions already taken
SInce AstroMain 3.37.1 (2021-02-24) calls from threads to change the UI  have largely been all changed over to use BeginInvoke calls, (instead of Invoke call or Direct calls to form components). This has allowed the threads like Obs.Monitor & Obs.Worker to continue working without having to wait indefinately for the UI to respond. 

New Actions

Update 2021-03-03  (AstroMain 3.38)
Several changes have now been implemented to try to solve the UI Hang-Up issue

Back to Top


Pulsar Dome - Software Update (Rotation 1.41)

Update 2021-03-04:

A new attempt to update the shutter software to v1.23 was made on 2021-03-04 (see notes). 

Back to Top