View Issue Details

IDProjectCategoryView StatusLast Update
0002527SkyChart1-Softwarepublic22-05-14 14:29
ReporterDavid Moore Assigned ToPatrick Chevalley  
PrioritynormalSeverityminorReproducibilityhave not tried
Status resolvedResolutionno change required 
Summary0002527: Cartes du Ciel suddenly takes 40 secs to open when it used to take about 3.
DescriptionAs above on a new fast 16GB laptop. Possibly since Win10 update. Tried deleting and reinstalling. Downloaded all star charts again but do not know how to enable them.
TagsNo tags attached.

Relationships

has duplicate 0002528 closed Cartes du Ciel started slowly in 40 secs. Trouble uploading trace file requested so he it is opened 

Activities

Patrick Chevalley

22-04-29 15:53

administrator   ~0007534

It is possible to create a detailed trace of what the program do during the startup to help to find the reason for this delay.
For that open a command prompt windows, use cd to change to the directory where skychart is installed, start the program with: skychart.exe --verbose
See attached screenshot.

Close the program, open the Windows file explorer, in the address bar type: %LOCALAPPDATA%
Open the skychart directory here, there is a file cdc_trace.txt, upload this file here.
skychart_verbose.png (8,612 bytes)   
skychart_verbose.png (8,612 bytes)   

Patrick Chevalley

22-04-29 17:20

administrator   ~0007537

Response from David Moore:

2022-04-29T15:06:39.146 Start trace
2022-04-29T15:06:39.146 Program version : 4.2.1-4073-ee8141c3
2022-04-29T15:06:39.146 Program compiled: 2019/11/24 15:48:06
2022-04-29T15:06:39.146 Compiler version: Lazarus 2.0.6.0 Free Pascal 3.0.4 Win64-x86_64-mswindows
2022-04-29T15:06:39.146 Screen scaling : True
2022-04-29T15:06:39.146 Screen scale : 139%
2022-04-29T15:06:39.146 Try language: en_GB, en
2022-04-29T15:06:39.161 Language: en
2022-04-29T15:07:15.770 Connected to the SQL database C:\Users\moore\AppData\Local\Skychart\database\cdc.db
2022-04-29T15:07:15.902 Listen on port: 3292

Please tell me if this arrived as your procedures are difficult to follow

Patrick Chevalley

22-04-29 17:37

administrator   ~0007538

Sorry for the insufficient instruction.

I copy your response in the original report, when replying you can click the link at the top of the email to add your response without opening a new issue.
This link also allow you to see the screenshot.

This is the right file, and it show a 35 second delay where it normally take less than one second.
But this is not the detailed verbose trace, there is about 45 individual steps between the message at 15:06:39 and the one at 15:07:15, so it is important for me to get this detail.

You can also set the verbose option by modifying the skychart shortcut on the desktop, maybe this is more easy than the command line.
Process as follow (also see the screenshot):
- right click the skychart icon on the desktop
- select Properties
- open the Shortcut tab
- in the "Target:" field add at the end --verbose
the full line must be : "C:\Program Files\Ciel\skychart.exe" --verbose
- click OK
- run skychart by a double click on this icon

Copy the new file cdc_trace.txt as you do.
skychart_shortcut.png (63,703 bytes)   
skychart_shortcut.png (63,703 bytes)   

David Moore

22-04-29 18:37

reporter   ~0007539

cdc_trace.txt (7,292 bytes)   
2022-04-29T17:25:36.698  Start trace
2022-04-29T17:25:36.698  Program version : 4.2.1-4073-ee8141c3
2022-04-29T17:25:36.698  Program compiled: 2019/11/24 15:48:06
2022-04-29T17:25:36.698  Compiler version: Lazarus 2.0.6.0 Free Pascal 3.0.4 Win64-x86_64-mswindows
2022-04-29T17:25:36.698  Privatedir: C:\Users\moore\AppData\Local\Skychart
2022-04-29T17:25:36.698  Appdir: C:\Program Files\Ciel
2022-04-29T17:25:36.698  Scaling
2022-04-29T17:25:36.713  Screen scaling : True
2022-04-29T17:25:36.713  Screen scale : 139%
2022-04-29T17:25:36.713  Language
2022-04-29T17:25:36.713  Try language: en_GB, en
2022-04-29T17:25:36.713  Language: en
2022-04-29T17:25:36.713  Multiframe
2022-04-29T17:25:36.713  Size control
2022-04-29T17:25:36.713  Load zlib
2022-04-29T17:25:36.713  Load plan404
2022-04-29T17:25:36.713  Load cdcwcs
2022-04-29T17:25:36.713  Multiframe border
2022-04-29T17:25:36.713  Bitmap
2022-04-29T17:25:36.713  Load timezone
2022-04-29T17:25:36.713  SAMP
2022-04-29T17:25:36.713  Toolbar
2022-04-29T17:25:36.713  Script panel
2022-04-29T17:25:36.730  Load Healpix library
2022-04-29T17:25:36.730  SetLang
2022-04-29T17:25:36.746  Exit Tf_main.FormCreate
2022-04-29T17:25:36.746  Create splash
2022-04-29T17:25:36.746  Show splash
2022-04-29T17:25:36.762  Create f_position
2022-04-29T17:25:36.777  Create f_search
2022-04-29T17:25:36.777  Create f_zoom
2022-04-29T17:25:36.777  Create f_getdss
2022-04-29T17:25:36.777  Loadlibrary libgetdss.dll
2022-04-29T17:25:36.777  Library ok
2022-04-29T17:25:36.777  Create f_manualtelescope
2022-04-29T17:25:36.777  Create f_detail
2022-04-29T17:25:36.777  Create f_info
2022-04-29T17:25:36.777  Create f_calendar
2022-04-29T17:25:36.777  Create Tf_image
2022-04-29T17:25:36.777  Create f_printsetup
2022-04-29T17:25:36.777  Create f_print
2022-04-29T17:25:36.777  Create f_obslist
2022-04-29T17:25:36.793  Create f_edittoolbar
2022-04-29T17:25:36.793  Create f_mosaic
2022-04-29T17:25:36.793  Main Init
2022-04-29T17:25:36.793  Enter Tf_main.Init
2022-04-29T17:25:36.793  SetDefault
2022-04-29T17:26:12.840  ReadDefault
2022-04-29T17:26:12.856  Create forms
2022-04-29T17:26:12.856  InitDS2000
2022-04-29T17:26:12.856  Load deltat
2022-04-29T17:26:12.856  Create DB
2022-04-29T17:26:12.856  Background Image
2022-04-29T17:26:12.856  Constellation
2022-04-29T17:26:13.204  Connect DB
2022-04-29T17:26:13.206  DB connected
2022-04-29T17:26:13.206  Connected to the SQL database C:\Users\moore\AppData\Local\Skychart\database\cdc.db
2022-04-29T17:26:13.206  Cursor
2022-04-29T17:26:13.206  Compass
2022-04-29T17:26:13.206  Starshape file
2022-04-29T17:26:13.206  Timezone
2022-04-29T17:26:13.214  Init calendar
2022-04-29T17:26:13.214  Create default chart
2022-04-29T17:26:13.214  Create new chart
2022-04-29T17:26:13.274  SkyChart : Init chart
2022-04-29T17:26:13.289  Read params
2022-04-29T17:26:13.289  Start server
2022-04-29T17:26:13.289  InitScript
2022-04-29T17:26:13.305  Socket error 10048 Address already in use.  
2022-04-29T17:26:13.305  Socket error 10022 Invalid argument.  
2022-04-29T17:26:13.305  Listen on port: 0 (different than the configured port, maybe busy or other error.)
2022-04-29T17:26:13.403  InitScript end
2022-04-29T17:26:13.403  Exit Tf_main.Init
2022-04-29T17:26:13.403  Application Run
2022-04-29T17:26:13.403  Chart_1 ChartResize
2022-04-29T17:26:13.403  Chart_1 ChartResize
2022-04-29T17:26:13.419  Enter Tf_main.FormShow
2022-04-29T17:26:13.419  Chart_1 ChartResize
2022-04-29T17:26:13.456  Chart_1 ChartResize
2022-04-29T17:26:13.456  Chart_1 ChartResize
2022-04-29T17:26:13.657  Exit Tf_main.FormShow
2022-04-29T17:26:13.657  ImageSetFocus
2022-04-29T17:26:13.757  Enter Tf_main.InitTimerTimer
2022-04-29T17:26:13.757  Exit Tf_main.InitTimerTimer
2022-04-29T17:26:14.073  Chart Chart_1: RefreshTimer
2022-04-29T17:26:14.073  Chart Chart_1: Refresh
2022-04-29T17:26:14.073  Chart Chart_1: Get refresh lock
2022-04-29T17:26:14.073  Chart Chart_1: Init 1942x1356
2022-04-29T17:26:14.073  Chart Chart_1: Draw map
2022-04-29T17:26:14.073  SkyChart Chart_1: Full Refresh
2022-04-29T17:26:14.073  SkyChart Chart_1: Init
2022-04-29T17:26:14.073  SkyChart Chart_1: Init observatory
2022-04-29T17:26:14.073  SkyChart Chart_1: Init time
2022-04-29T17:26:14.073  SkyChart Chart_1: Init chart
2022-04-29T17:26:14.073  SkyChart Chart_1: Init coordinates
2022-04-29T17:26:14.073  SkyChart Chart_1: Compute planet position
2022-04-29T17:26:14.073  SkyChart Chart_1: end Compute planet position
2022-04-29T17:26:14.073  SkyChart Chart_1: Init labels
2022-04-29T17:26:14.073  SkyChart Chart_1: Init colors
2022-04-29T17:26:14.073  SkyChart Chart_1: Open catalogs
2022-04-29T17:26:14.073  SkyChart Chart_1: Init catalogs
2022-04-29T17:26:14.073  SkyChart Chart_1: begin drawing
2022-04-29T17:26:14.073  SkyChart Chart_1: draw milky way
2022-04-29T17:26:14.089  SkyChart Chart_1: draw alt/az EQ grid
2022-04-29T17:26:14.089  SkyChart Chart_1: draw deepsky objects
2022-04-29T17:26:14.105  SkyChart Chart_1: draw outlines
2022-04-29T17:26:14.105  SkyChart Chart_1: draw comets
2022-04-29T17:26:14.105  SkyChart Chart_1: draw grid
2022-04-29T17:26:14.105  SkyChart Chart_1: draw constellation figures
2022-04-29T17:26:14.121  SkyChart Chart_1: draw stars
2022-04-29T17:26:14.174  SkyChart Chart_1: draw double stars
2022-04-29T17:26:14.174  SkyChart Chart_1: draw variable stars
2022-04-29T17:26:14.174  SkyChart Chart_1: draw planets
2022-04-29T17:26:14.174  Created C:\Users\moore\AppData\Local\Skychart\tmp\origin.txt
2022-04-29T17:26:14.174  SkyChart Chart_1: draw labels
2022-04-29T17:26:14.174  SkyChart Chart_1: Optimize labels
2022-04-29T17:26:14.174  SkyChart Chart_1: Labels optimized
2022-04-29T17:26:14.227  SkyChart Chart_1: draw horizon
2022-04-29T17:26:14.258  SkyChart Chart_1: draw grid
2022-04-29T17:26:14.275  SkyChart Chart_1: draw chart border
2022-04-29T17:26:14.275  SkyChart Chart_1: end drawing
2022-04-29T17:26:14.275  SkyChart Chart_1: end Refresh
2022-04-29T17:26:14.275  Chart Chart_1: Draw map end
2022-04-29T17:26:14.275  Chart Chart_1: Release refresh lock
2022-04-29T17:26:14.275  Chart Chart_1: Refresh end
2022-04-29T17:26:23.147  Chart_1 MouseDown
2022-04-29T17:26:23.147  SetChildFocus Chart_1
2022-04-29T17:26:23.147  ImageSetFocus
2022-04-29T17:26:23.147  ImageSetFocus
2022-04-29T17:26:23.364  Chart_1 Image1Click
2022-04-29T17:26:23.364  SetChildFocus Chart_1
2022-04-29T17:26:23.364  ImageSetFocus
2022-04-29T17:26:23.364  ImageSetFocus
2022-04-29T17:26:23.364  Chart_1 MouseUp
2022-04-29T17:26:23.364  SkyChart Chart_1: Init catalogs
2022-04-29T17:26:23.364  SkyChart Chart_1: Init catalogs
2022-04-29T17:27:13.301  AutorefreshTimer
2022-04-29T17:28:13.309  AutorefreshTimer
2022-04-29T17:29:13.310  AutorefreshTimer
2022-04-29T17:30:13.307  AutorefreshTimer
2022-04-29T17:31:03.082  Stop TCP/IP server
2022-04-29T17:31:03.883  Exiting ...
2022-04-29T17:31:03.883  Destroy Tf_main
2022-04-29T17:31:03.899  Destroy Cursor
2022-04-29T17:31:03.899  Destroy end
2022-04-29T17:31:03.915  Destroy chart Chart_1
2022-04-29T17:31:03.946  End Destroy chart 
2022-04-29T17:31:07.277  Stop TCP/IP server
2022-04-29T17:31:08.078  Exiting ...
cdc_trace.txt (7,292 bytes)   

Patrick Chevalley

22-04-29 21:36

administrator   ~0007540

Thank you for the file, exactly what I need.

I am surprised by the location of the problem, a very simple function that set the program default values before to read the saved configuration.
The only non-trivial action is to ask Windows for an eventual default printer.
I try to reproduce the problem with different printer configuration but without success.

To be sure this is really the issue I make a test version where I remove this printer detection.
Can you install skychart-4.3-4496-windows-x64.exe from https://vega.ap-i.net/tmp/skychart/
Then test if it start normally.

David Moore

22-04-29 23:13

reporter   ~0007541

The Beta version took the same length of time to open. Could it be a problem with my recent router change? The trace log mentions socket errors. I had to connect the printer to the new router and set the password. Printer is currently off. I got this reply from a guy that runs a tech firm. Maybe it helps, I don't understand most of it.

djanello@gmail.com0.00
djanello@gmail.com#2 days ago· 27 views
I run a tech firm, this behavior can arise from a network misconfiguration of the hostname on your PC or in the settings of your wifi router. It seems to impact Windows more than Linux/MacOS, especially the newer Windows 10 upgrades.

Even apps without networking can experience slowdowns.

This thread contains a possible solution.
https://social.technet.microsoft.com/Forums/en-US/4067fa31-81b6-47a6-92c6-2395aa42a118/reverse-dns-slow-or-not-working-at-all?forum=winserverPN

David Moore

22-05-05 15:28

reporter   ~0007546

Hello - I hope you haven't given up on me as this problem is a pain. I had to start again from scratch outside in the early hrs of the morning and having to wait 40 secs is a pain. I have tried deleting AVG free and it makes no difference. I have tried reinstalling my other router but it seems to have failed so that's no good. I have reverted to the standard download instead of Beta with no change. Beta version was also 35 sec enable time. My workstation with Cartes normal version starts up in a few secs. Is it something remaining in the registry on my laptop that remains when I delete Cartes and reinstall? If so what can I do, there are so many entries. I I fired up Cartes on my laptop and workstation and made sure all the setup entries were the same but it didn't help. Basically I am out of my depth and still need help please.
Best regards,

David Moore

Patrick Chevalley

22-05-05 16:14

administrator   ~0007547

Hello David,

I am still searching in the CdC startup. I make a change that can save about one second on the startup time, but probably not related to your issue.

It is a good idea to try to totally reset CdC in the problematic computer.
CdC do not use the registry but only files in the directory %LOCALAPPDATA%\skychart (the place you find the trace file).
If you delete this directory you will start totally fresh.

Patrick

David Moore

22-05-08 18:06

reporter   ~0007548

I tried deleting the dir you mentioned in Ciel with no change. As my workstation has the same version and you tell me there are no registry entries, I copied all of the ciel dir to a memory stick, deleted Cartes from my laptop and copied the ciel dir to my programs dir. It still started in 35 secs. I tries disabling AVG, no change. Something has happened on my laptop to slow Cartes loading and I don't know what it is. When I go into task manager as it is loading there are no signs of a problem. I don't have any extra star maps loaded. I am stuck!! Does this happen to anyone else?

David Moore

22-05-14 07:53

reporter   ~0007563

I have solved this problem but reinstalling windows and deleting all progs and data. A bit drastic but nothing else worked. I had tried deleting some programs but that had no effect. At least it was easy to do but reinstalling everything is taking awhile with all the necessary passwords and fending off all the junk installations and intrusive software.

David Moore

Patrick Chevalley

22-05-14 14:29

administrator   ~0007564

Thank you for the information.
Yes this is a bit drastic and unfortunately not show the origin of the problem.
But the important point is it is back running normally.

Issue History

Date Modified Username Field Change
22-04-27 14:40 David Moore New Issue
22-04-29 15:49 Patrick Chevalley Project CCdciel => SkyChart
22-04-29 15:53 Patrick Chevalley Note Added: 0007534
22-04-29 15:53 Patrick Chevalley File Added: skychart_verbose.png
22-04-29 15:53 Patrick Chevalley Assigned To => Patrick Chevalley
22-04-29 15:53 Patrick Chevalley Status new => feedback
22-04-29 17:19 Patrick Chevalley Relationship added has duplicate 0002528
22-04-29 17:20 Patrick Chevalley Note Added: 0007537
22-04-29 17:37 Patrick Chevalley Note Added: 0007538
22-04-29 17:37 Patrick Chevalley File Added: skychart_shortcut.png
22-04-29 18:37 David Moore Note Added: 0007539
22-04-29 18:37 David Moore File Added: cdc_trace.txt
22-04-29 18:37 David Moore Status feedback => assigned
22-04-29 21:36 Patrick Chevalley Note Added: 0007540
22-04-29 21:37 Patrick Chevalley Status assigned => feedback
22-04-29 23:13 David Moore Note Added: 0007541
22-04-29 23:13 David Moore Status feedback => assigned
22-05-05 15:28 David Moore Note Added: 0007546
22-05-05 16:14 Patrick Chevalley Note Added: 0007547
22-05-08 18:06 David Moore Note Added: 0007548
22-05-14 07:53 David Moore Note Added: 0007563
22-05-14 14:29 Patrick Chevalley Status assigned => resolved
22-05-14 14:29 Patrick Chevalley Resolution open => no change required
22-05-14 14:29 Patrick Chevalley Category General => 1-Software
22-05-14 14:29 Patrick Chevalley Note Added: 0007564