RhinoStart: Initial startup...(RH-79171 1) - Not Fixed

Alan_Farkas · May 29, 2024, 2:40am

@brian Rhino 8 Service Release Candidate Available - #38 by brian

Just installed update from today. Rhino 8 still surprisingly slow in its startup.

SysInfo

Rhino 8 SR8 2024-5-28 (Rhino 8, 8.8.24149.15001, Git hash:master @ 49367284ece6544c4e32d4d2fce81c37a1bd9dff)
License type: Commercial, build 2024-05-28
License details: Cloud Zoo

Windows 11 (10.0.22631 SR0.0) or greater (Physical RAM: 32GB)
.NET 7.0.19

Computer platform: DESKTOP

Standard graphics configuration.
Primary display and OpenGL: NVIDIA GeForce RTX 3060 (NVidia) Memory: 12GB, Driver date: 4-11-2024 (M-D-Y). OpenGL Ver: 4.6.0 NVIDIA 552.22
> Accelerated graphics device with 4 adapter port(s)
- Windows Main Display attached to adapter port 0

Secondary graphics devices.
NVIDIA GeForce RTX 2070 (NVidia) Memory: 8GB, Driver date: 4-11-2024 (M-D-Y).
> Accelerated graphics device with 4 adapter port(s)
- Secondary monitor attached to adapter port 0

OpenGL Settings
Safe mode: Off
Use accelerated hardware modes: On
Redraw scene when viewports are exposed: On
Graphics level being used: OpenGL 4.6 (primary GPU’s maximum)

Anti-alias mode: 4x
Mip Map Filtering: Linear
Anisotropic Filtering Mode: High

Vendor Name: NVIDIA Corporation
Render version: 4.6
Shading Language: 4.60 NVIDIA
Driver Date: 4-11-2024
Driver Version: 31.0.15.5222
Maximum Texture size: 32768 x 32768
Z-Buffer depth: 24 bits
Maximum Viewport size: 32768 x 32768
Total Video Memory: 12 GB

Rhino plugins that do not ship with Rhino
C:\ProgramData\McNeel\Rhinoceros\8.0\Plug-ins\Datasmith Rhino Exporter (d1fdc795-b334-4933-b680-088119cdc6bb)\DatasmithRhino8.rhp “Datasmith Exporter” 5.4.1.0
C:\Users\alanf\AppData\Roaming\McNeel\Rhinoceros\packages\8.0\Cephalopod\0.0.3.0\Cephalopod.rhp “Cephalopod” 0.0.1.0
C:\Users\alanf\AppData\Roaming\McNeel\Rhinoceros\8.0\Plug-ins\BlockEditNew\BlockEditNew.rhp “BlockEdit” 1.0.0.0
C:\Users\alanf\AppData\Roaming\McNeel\Rhinoceros\packages\8.0\rhinopbrmaterial\1.1.4+v6.16\RhinoPbrMaterial.rhp “RhinoPbrMaterial” 1.1.2.0
C:\Users\alanf\AppData\Roaming\McNeel\Rhinoceros\packages\8.0\SubstanceImporter\2.0.7\Substance.Win.rhp “SubstanceImporter” 2.0.7.0

Rhino plugins that ship with Rhino
C:\Program Files\Rhino 8\Plug-ins\Commands.rhp “Commands” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\rdk.rhp “Renderer Development Kit”
C:\Program Files\Rhino 8\Plug-ins\RhinoScript.rhp “RhinoScript”
C:\Program Files\Rhino 8\Plug-ins\RhinoBonusTools.rhp “Rhino Bonus Tools”
C:\Program Files\Rhino 8\Plug-ins\AnimationTools.rhp “AnimationTools”
C:\Program Files\Rhino 8\Plug-ins\RhinoRenderCycles.rhp “Rhino Render” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\RhinoRender.rhp “Legacy Rhino Render”
C:\Program Files\Rhino 8\Plug-ins\rdk_etoui.rhp “RDK_EtoUI” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\NamedSnapshots.rhp “Snapshots”
C:\Program Files\Rhino 8\Plug-ins\MeshCommands.rhp “MeshCommands” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\RhinoCycles.rhp “RhinoCycles” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\Toolbars\Toolbars.rhp “Toolbars” 8.8.24149.15001
C:\Program Files\Rhino 8\Plug-ins\3dxrhino.rhp “3Dconnexion 3D Mouse”
C:\Program Files\Rhino 8\Plug-ins\Displacement.rhp “Displacement”
C:\Program Files\Rhino 8\Plug-ins\SectionTools.rhp “SectionTools”

_RhinoBiitmapCreator_SetDesignMasterDB.3dm (433.3 KB)

Helvetosaur · May 29, 2024, 6:18am

I’m afraid that’s the new norm for now with the first start after an update. Rhino spends a lot of time loading all the new Python stuff. Other plug-ins might also contribute to the slow startup.

Alan_Farkas · May 29, 2024, 11:35pm

…and the second,…and third…and 9th… Sigh.

Helvetosaur · May 30, 2024, 5:24am

If Rhino is slow starting every time, there is something else going on… are you running any non-standard plug-ins?

nathanletwory · May 30, 2024, 5:41am

Add to the shortcut through which you start Rhino /stopwatch.

After a startup has completed hit F2 (or run _CommandHistory in case you have rebound F2) to get a listing of timings.

The output looks like this

(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(0.6850) - Since Last Record = 0.6850 (%8.86) - after create and load AppSettings
(0.6860) - Since Last Record = 0.0010 (%0.01) - before CRhWGLExtensions::InitWGLExtensions()
(0.8870) - Since Last Record = 0.2010 (%2.60) - after CRhWGLExtensions::InitWGLExtensions()
(0.8910) - Since Last Record = 0.0040 (%0.05) - construct CMainFrame
(1.4390) - Since Last Record = 0.5480 (%7.09) - after load CMainFrame
(2.4480) - Since Last Record = 1.0090 (%13.05) - after initialize license manager
(2.4490) - Since Last Record = 0.0010 (%0.01) - after log events
(2.4500) - Since Last Record = 0.0010 (%0.01) - after registering shell file types
(2.4500) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(2.4600) - Since Last Record = 0.0100 (%0.13) - after loading of cursor resources
(2.4980) - Since Last Record = 0.0380 (%0.49) - after RegisterBuiltInCommands()
(2.5260) - Since Last Record = 0.0280 (%0.36) - Pre auto load plugins
(2.5540) - Since Last Record = 0.0280 (%0.36) - Toolbars
(2.5560) - Since Last Record = 0.0020 (%0.03) - RDK:CRhRdkRhinoPlugIn CTOR
(2.5560) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn
(2.5590) - Since Last Record = 0.0030 (%0.04) - RDK:Initialize: start
(2.5590) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(2.5660) - Since Last Record = 0.0070 (%0.09) - RDK:Initialize: after default post effect plug-in factories
(2.5660) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(2.5660) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(2.5750) - Since Last Record = 0.0090 (%0.12) - RDK:Initialize: end
(2.5750) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(2.5750) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(2.5780) - Since Last Record = 0.0030 (%0.04) - RDK:OnLoadPlugIn - end
(2.6290) - Since Last Record = 0.0510 (%0.66) - Renderer Development Kit
(2.7860) - Since Last Record = 0.1570 (%2.03) - Commands
(2.7990) - Since Last Record = 0.0130 (%0.17) - Snapshots
(6.4140) - Since Last Record = 3.6150 (%46.74) - Bongo 3.0
(6.4250) - Since Last Record = 0.0110 (%0.14) - Rhino Render
(6.5120) - Since Last Record = 0.0870 (%1.12) - RDK_EtoUI
(6.5290) - Since Last Record = 0.0170 (%0.22) - MeshCommands
(6.6180) - Since Last Record = 0.0890 (%1.15) - RhinoCycles
(6.6360) - Since Last Record = 0.0180 (%0.23) - 3Dconnexion 3D Mouse
(6.6470) - Since Last Record = 0.0110 (%0.14) - Displacement
(6.6620) - Since Last Record = 0.0150 (%0.19) - SectionTools
(6.7090) - Since Last Record = 0.0470 (%0.61) - BongoEtoUI
(6.7160) - Since Last Record = 0.0070 (%0.09) - HDRLightStudioTexture
(6.7240) - Since Last Record = 0.0080 (%0.10) - SubstanceImporter
(6.7240) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.7240) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.7250) - Since Last Record = 0.0010 (%0.01) - after create TabbedDockBarCollection
(6.7250) - Since Last Record = 0.0000 (%0.00) - after load default render engine
(6.7250) - Since Last Record = 0.0000 (%0.00) - after CloseAllDocuments
(6.8630) - Since Last Record = 0.1380 (%1.78) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(6.8640) - Since Last Record = 0.0010 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.0660) - Since Last Record = 0.2020 (%2.61) - Grasshopper
(7.0720) - Since Last Record = 0.0060 (%0.08) - RhiPPI
(7.0720) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.0720) - Since Last Record = 0.0000 (%0.00) - after  m_pCallbacks->LoadToolBarFiles
(7.0720) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(7.4800) - Since Last Record = 0.4080 (%5.28) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(7.4800) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(7.4840) - Since Last Record = 0.0040 (%0.05) - before Process Shell Commands
(7.7280) - Since Last Record = 0.2440 (%3.15) - after Process Shell Commands
(7.7340) - Since Last Record = 0.0060 (%0.08) - CRhStopWatch::ShowReport()
Command: _CommandHistory

Run again and post the results here.

Alan_Farkas · May 30, 2024, 6:21am

I am actually running a couple plugins that may be considered non-standard. That said, they didn’t used to have any negative effect. I’ll list them in the morning, thx

I’ll def run and post that @nathanletwory thx

Alan_Farkas · May 30, 2024, 6:36pm

Hey @nathanletwory - you have me intrigued by the /nosplash

Is there anything after that addition that I would need to know in order to add that to my shortcut?

And here’s the stopwatch results. I wasn’t sure what you meant about “run it again” so I just closed Rhino and then started it up again, each time grabbing the stopwatch results. I did that with both a Basic Template, and a Custom Template, and I’ve pasted the results for that 2nd Run for each here. Not sure what’s hanging up the basic template, but there’s a doozy on the custom one!

2nd Run - Basic inches, Feet-Inches Template

(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(1.2930) - Since Last Record = 1.2930 (%15.57) - after create and load AppSettings
(1.2960) - Since Last Record = 0.0030 (%0.04) - before CRhWGLExtensions::InitWGLExtensions()
(1.6710) - Since Last Record = 0.3750 (%4.52) - after CRhWGLExtensions::InitWGLExtensions()
(1.6820) - Since Last Record = 0.0110 (%0.13) - before Create splash window
(1.6820) - Since Last Record = 0.0000 (%0.00) - after Create splash window
(1.6870) - Since Last Record = 0.0050 (%0.06) - construct CMainFrame
(2.5690) - Since Last Record = 0.8820 (%10.62) - after load CMainFrame
(2.8730) - Since Last Record = 0.3040 (%3.66) - after initialize license manager
(3.2880) - Since Last Record = 0.4150 (%5.00) - after log events
(3.2930) - Since Last Record = 0.0050 (%0.06) - after registering shell file types
(3.2930) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(3.3260) - Since Last Record = 0.0330 (%0.40) - after loading of cursor resources
(3.4050) - Since Last Record = 0.0790 (%0.95) - after RegisterBuiltInCommands()
(3.4890) - Since Last Record = 0.0840 (%1.01) - Pre auto load plugins
(3.5420) - Since Last Record = 0.0530 (%0.64) - Toolbars
(3.5620) - Since Last Record = 0.0200 (%0.24) - RDK:CRhRdkRhinoPlugIn CTOR
(3.5640) - Since Last Record = 0.0020 (%0.02) - RDK:OnLoadPlugIn
(3.5700) - Since Last Record = 0.0060 (%0.07) - RDK:Initialize: start
(3.5700) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(3.5840) - Since Last Record = 0.0140 (%0.17) - RDK:Initialize: after default post effect plug-in factories
(3.5840) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(3.5840) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(3.5990) - Since Last Record = 0.0150 (%0.18) - RDK:Initialize: end
(3.5990) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(3.5990) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(3.6040) - Since Last Record = 0.0050 (%0.06) - RDK:OnLoadPlugIn - end
(3.7460) - Since Last Record = 0.1420 (%1.71) - Renderer Development Kit
(4.0770) - Since Last Record = 0.3310 (%3.99) - Commands
(4.1460) - Since Last Record = 0.0690 (%0.83) - Snapshots
(4.2100) - Since Last Record = 0.0640 (%0.77) - Rhino Render
(4.3720) - Since Last Record = 0.1620 (%1.95) - RDK_EtoUI
(4.4410) - Since Last Record = 0.0690 (%0.83) - MeshCommands
(4.7870) - Since Last Record = 0.3460 (%4.17) - RhinoCycles
(4.8530) - Since Last Record = 0.0660 (%0.79) - 3Dconnexion 3D Mouse
(4.9220) - Since Last Record = 0.0690 (%0.83) - Displacement
(4.9860) - Since Last Record = 0.0640 (%0.77) - SectionTools
(5.5020) - Since Last Record = 0.5160 (%6.21) - Datasmith Exporter
(5.5400) - Since Last Record = 0.0380 (%0.46) - BlockEdit
(5.5600) - Since Last Record = 0.0200 (%0.24) - RhinoPbrMaterial
(5.5860) - Since Last Record = 0.0260 (%0.31) - SubstanceImporter
(5.5860) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(5.5860) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(5.5870) - Since Last Record = 0.0010 (%0.01) - after create TabbedDockBarCollection
(5.5880) - Since Last Record = 0.0010 (%0.01) - after load default render engine
(5.5890) - Since Last Record = 0.0010 (%0.01) - after CloseAllDocuments
(5.6690) - Since Last Record = 0.0800 (%0.96) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(5.6700) - Since Last Record = 0.0010 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(6.3770) - Since Last Record = 0.7070 (%8.51) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(6.3770) - Since Last Record = 0.0000 (%0.00) - after m_pCallbacks->LoadToolBarFiles
(6.3770) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(7.3840) - Since Last Record = 1.0070 (%12.13) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(7.3840) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(7.3870) - Since Last Record = 0.0030 (%0.04) - before Process Shell Commands
(8.2910) - Since Last Record = 0.9040 (%10.89) - after Process Shell Commands
(8.3030) - Since Last Record = 0.0120 (%0.14) - CRhStopWatch::ShowReport()
Command: _SetDimensionLayer
Specify dimension layer options ( UseDimensionLayer=No DimensionLayer= ): _D
Dimension layer name ( List Reset ): Layout::03_Dimensions
Specify dimension layer options ( UseDimensionLayer=Yes DimensionLayer=Layout::03_Dimensions ): _Enter
Specify dimension layer options ( UseDimensionLayer=Yes DimensionLayer=Layout::03_Dimensions )
Command: _CommandHistory

2nd Run - Custom Template

Loading Legacy Rhino Render, version 1.50, May 28 2024, 15:16:35
Read 2 annotation styles and 7 annotation objects that reference 1 font that is not installed on this device.
Annotation styles referencing a missing font:
AMF_1/4" (48 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
AMF_1/2" (24 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
Missing font:
Metrostyle Extended [MetrostyleExtended-Regular]
(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(1.0560) - Since Last Record = 1.0560 (%5.94) - after create and load AppSettings
(1.0570) - Since Last Record = 0.0010 (%0.01) - before CRhWGLExtensions::InitWGLExtensions()
(1.4050) - Since Last Record = 0.3480 (%1.96) - after CRhWGLExtensions::InitWGLExtensions()
(1.4110) - Since Last Record = 0.0060 (%0.03) - before Create splash window
(1.4120) - Since Last Record = 0.0010 (%0.01) - after Create splash window
(1.4160) - Since Last Record = 0.0040 (%0.02) - construct CMainFrame
(2.2450) - Since Last Record = 0.8290 (%4.66) - after load CMainFrame
(2.5420) - Since Last Record = 0.2970 (%1.67) - after initialize license manager
(3.2490) - Since Last Record = 0.7070 (%3.98) - after log events
(3.2530) - Since Last Record = 0.0040 (%0.02) - after registering shell file types
(3.2530) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(3.2900) - Since Last Record = 0.0370 (%0.21) - after loading of cursor resources
(3.3540) - Since Last Record = 0.0640 (%0.36) - after RegisterBuiltInCommands()
(3.4370) - Since Last Record = 0.0830 (%0.47) - Pre auto load plugins
(3.4860) - Since Last Record = 0.0490 (%0.28) - Toolbars
(3.4930) - Since Last Record = 0.0070 (%0.04) - RDK:CRhRdkRhinoPlugIn CTOR
(3.4950) - Since Last Record = 0.0020 (%0.01) - RDK:OnLoadPlugIn
(3.4980) - Since Last Record = 0.0030 (%0.02) - RDK:Initialize: start
(3.4980) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(3.5110) - Since Last Record = 0.0130 (%0.07) - RDK:Initialize: after default post effect plug-in factories
(3.5110) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(3.5110) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(3.5270) - Since Last Record = 0.0160 (%0.09) - RDK:Initialize: end
(3.5270) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(3.5270) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(3.5310) - Since Last Record = 0.0040 (%0.02) - RDK:OnLoadPlugIn - end
(3.6890) - Since Last Record = 0.1580 (%0.89) - Renderer Development Kit
(4.0190) - Since Last Record = 0.3300 (%1.86) - Commands
(4.0730) - Since Last Record = 0.0540 (%0.30) - Snapshots
(4.1220) - Since Last Record = 0.0490 (%0.28) - Rhino Render
(4.2830) - Since Last Record = 0.1610 (%0.91) - RDK_EtoUI
(4.3450) - Since Last Record = 0.0620 (%0.35) - MeshCommands
(4.6590) - Since Last Record = 0.3140 (%1.77) - RhinoCycles
(4.7220) - Since Last Record = 0.0630 (%0.35) - 3Dconnexion 3D Mouse
(4.7790) - Since Last Record = 0.0570 (%0.32) - Displacement
(4.8400) - Since Last Record = 0.0610 (%0.34) - SectionTools
(5.3600) - Since Last Record = 0.5200 (%2.92) - Datasmith Exporter
(5.4060) - Since Last Record = 0.0460 (%0.26) - BlockEdit
(5.4250) - Since Last Record = 0.0190 (%0.11) - RhinoPbrMaterial
(5.4490) - Since Last Record = 0.0240 (%0.13) - SubstanceImporter
(5.4490) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(5.4490) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(5.4500) - Since Last Record = 0.0010 (%0.01) - after create TabbedDockBarCollection
(5.4510) - Since Last Record = 0.0010 (%0.01) - after load default render engine
(5.4530) - Since Last Record = 0.0020 (%0.01) - after CloseAllDocuments
(5.5320) - Since Last Record = 0.0790 (%0.44) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(5.5340) - Since Last Record = 0.0020 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(6.1530) - Since Last Record = 0.6190 (%3.48) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(6.1530) - Since Last Record = 0.0000 (%0.00) - after m_pCallbacks->LoadToolBarFiles
(6.1530) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(7.1420) - Since Last Record = 0.9890 (%5.56) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(7.1420) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(7.1440) - Since Last Record = 0.0020 (%0.01) - before Process Shell Commands
(16.5730) - Since Last Record = 9.4290 (%53.04) - RhinoScript
(16.6380) - Since Last Record = 0.0650 (%0.37) - Rhino Bonus Tools
(16.7090) - Since Last Record = 0.0710 (%0.40) - AnimationTools
(16.7840) - Since Last Record = 0.0750 (%0.42) - Legacy Rhino Render
(17.7640) - Since Last Record = 0.9800 (%5.51) - after Process Shell Commands
(17.7780) - Since Last Record = 0.0140 (%0.08) - CRhStopWatch::ShowReport()
Command: _SetDimensionLayer
Specify dimension layer options ( UseDimensionLayer=No DimensionLayer= ): _D
Dimension layer name ( List Reset ): Layout::03_Dimensions
Specify dimension layer options ( UseDimensionLayer=Yes DimensionLayer=Layout::03_Dimensions ): _Enter
Specify dimension layer options ( UseDimensionLayer=Yes DimensionLayer=Layout::03_Dimensions )
Command: _CommandHistory

Thanks for your help,

Alan

nathanletwory · May 30, 2024, 7:12pm

Not really. I use /nosplash because I don’t like the splash screen

I just meant that after adding the stopwatch argument you can Rhino (… again), and see what the results are.

For better comparison you should run twice with the same template. Anyway, as mentioned, first start after an update typically will take time. You can see with the stopwatch what exactly - not that you can do much about it, but it may help you understand. Subsequent starts of Rhino should be faster. Templates shouldn’t matter here much if at all.

Alan_Farkas · May 30, 2024, 7:44pm

Ah, I posted then deleted the 1st Run for each.

Are you able to make heads or tails out of these (above?) There’s definitely a difference between the two templates! Particularly the 9+sec line in Custom Template where I bolded and marked it with some

Thx

Alan

nathanletwory · May 30, 2024, 9:04pm

Does this 9 second delay happen every time you open this template - how do you open this specific template btw?

For me Rhino always opens in default settings, only after it has started can I start a new document to pick a template.

Do you have any startup commands?

Alan_Farkas · May 30, 2024, 9:24pm

Yes, every time.
Usually, I have it set as my default in the Options > Files > Default:

or like this from the New From Template window:

At the moment I have one start-up command to set my Dimensions to a specific layer.

I should note that this has been my setup for years now, and this is the first time there’s been any problem with the startup times. In fact, just a few months ago, Rhino would open on this Template so fast I couldn’t even click the Splash screen to choose an alternate file to open!

Alan_Farkas · May 30, 2024, 11:53pm

Updated (but not better) results after paring down my template.

Got rid of Startup command
Uninstalled non-essential plugins
Pared down Layers
Deleted all Blocks
Disabled RhinoScript (8.6350 sec)
Disabled RhinoScriptEditor

Pared-down Custom - 1st Run

Loading Legacy Rhino Render, version 1.50, May 28 2024, 15:16:35
Read 2 annotation styles that reference 1 font that is not installed on this device.
Annotation styles referencing a missing font:
AMF_1/4" (48 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
AMF_1/2" (24 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
Missing font:
Metrostyle Extended [MetrostyleExtended-Regular]
(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(1.0080) - Since Last Record = 1.0080 (%5.85) - after create and load AppSettings
(1.0090) - Since Last Record = 0.0010 (%0.01) - before CRhWGLExtensions::InitWGLExtensions()
(1.3720) - Since Last Record = 0.3630 (%2.11) - after CRhWGLExtensions::InitWGLExtensions()
(1.3780) - Since Last Record = 0.0060 (%0.03) - before Create splash window
(1.3780) - Since Last Record = 0.0000 (%0.00) - after Create splash window
(1.4040) - Since Last Record = 0.0260 (%0.15) - construct CMainFrame
(2.2420) - Since Last Record = 0.8380 (%4.86) - after load CMainFrame
(2.5380) - Since Last Record = 0.2960 (%1.72) - after initialize license manager
(4.4910) - Since Last Record = 1.9530 (%11.34) - after log events
(4.4960) - Since Last Record = 0.0050 (%0.03) - after registering shell file types
(4.4960) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(4.5290) - Since Last Record = 0.0330 (%0.19) - after loading of cursor resources
(4.5930) - Since Last Record = 0.0640 (%0.37) - after RegisterBuiltInCommands()
(4.6650) - Since Last Record = 0.0720 (%0.42) - Pre auto load plugins
(4.7050) - Since Last Record = 0.0400 (%0.23) - Toolbars
(4.7100) - Since Last Record = 0.0050 (%0.03) - RDK:CRhRdkRhinoPlugIn CTOR
(4.7110) - Since Last Record = 0.0010 (%0.01) - RDK:OnLoadPlugIn
(4.7200) - Since Last Record = 0.0090 (%0.05) - RDK:Initialize: start
(4.7200) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(4.7330) - Since Last Record = 0.0130 (%0.08) - RDK:Initialize: after default post effect plug-in factories
(4.7330) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(4.7330) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(4.7490) - Since Last Record = 0.0160 (%0.09) - RDK:Initialize: end
(4.7490) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(4.7490) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(4.7530) - Since Last Record = 0.0040 (%0.02) - RDK:OnLoadPlugIn - end
(4.8560) - Since Last Record = 0.1030 (%0.60) - Renderer Development Kit
(5.1350) - Since Last Record = 0.2790 (%1.62) - Commands
(5.1900) - Since Last Record = 0.0550 (%0.32) - Snapshots
(5.2400) - Since Last Record = 0.0500 (%0.29) - Rhino Render
(5.3900) - Since Last Record = 0.1500 (%0.87) - RDK_EtoUI
(5.4470) - Since Last Record = 0.0570 (%0.33) - MeshCommands
(5.7120) - Since Last Record = 0.2650 (%1.54) - RhinoCycles
(5.7750) - Since Last Record = 0.0630 (%0.37) - 3Dconnexion 3D Mouse
(5.8190) - Since Last Record = 0.0440 (%0.26) - Displacement
(5.8740) - Since Last Record = 0.0550 (%0.32) - SectionTools
(6.3600) - Since Last Record = 0.4860 (%2.82) - Datasmith Exporter
(6.3910) - Since Last Record = 0.0310 (%0.18) - BlockEdit
(6.4060) - Since Last Record = 0.0150 (%0.09) - RhinoPbrMaterial
(6.4260) - Since Last Record = 0.0200 (%0.12) - SubstanceImporter
(6.4260) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.4260) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.4270) - Since Last Record = 0.0010 (%0.01) - after create TabbedDockBarCollection
(6.4280) - Since Last Record = 0.0010 (%0.01) - after load default render engine
(6.4310) - Since Last Record = 0.0030 (%0.02) - after CloseAllDocuments
(6.5060) - Since Last Record = 0.0750 (%0.44) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(6.5070) - Since Last Record = 0.0010 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.2030) - Since Last Record = 0.6960 (%4.04) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.2030) - Since Last Record = 0.0000 (%0.00) - after m_pCallbacks->LoadToolBarFiles
(7.2030) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(8.1150) - Since Last Record = 0.9120 (%5.29) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(8.1150) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(8.1170) - Since Last Record = 0.0020 (%0.01) - before Process Shell Commands
(16.7520) - Since Last Record = 8.6350 (%50.12) - Rhino Bonus Tools
(16.8000) - Since Last Record = 0.0480 (%0.28) - AnimationTools
(16.8620) - Since Last Record = 0.0620 (%0.36) - Legacy Rhino Render
(17.2150) - Since Last Record = 0.3530 (%2.05) - after Process Shell Commands
(17.2270) - Since Last Record = 0.0120 (%0.07) - CRhStopWatch::ShowReport()
Command: _CommandHistory
Command: _Options
Command: _CommandHistory

Next up, Disabled:

Disabled RhinoBonusTools+others

Read 2 annotation styles that reference 1 font that is not installed on this device.
Annotation styles referencing a missing font:
AMF_1/4" (48 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
AMF_1/2" (24 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
Missing font:
Metrostyle Extended [MetrostyleExtended-Regular]
(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(0.9800) - Since Last Record = 0.9800 (%5.44) - after create and load AppSettings
(0.9810) - Since Last Record = 0.0010 (%0.01) - before CRhWGLExtensions::InitWGLExtensions()
(1.3110) - Since Last Record = 0.3300 (%1.83) - after CRhWGLExtensions::InitWGLExtensions()
(1.3170) - Since Last Record = 0.0060 (%0.03) - before Create splash window
(1.3170) - Since Last Record = 0.0000 (%0.00) - after Create splash window
(1.3210) - Since Last Record = 0.0040 (%0.02) - construct CMainFrame
(2.1700) - Since Last Record = 0.8490 (%4.72) - after load CMainFrame
(2.4620) - Since Last Record = 0.2920 (%1.62) - after initialize license manager
(5.1280) - Since Last Record = 2.6660 (%14.81) - after log events
(5.1310) - Since Last Record = 0.0030 (%0.02) - after registering shell file types
(5.1310) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(5.1500) - Since Last Record = 0.0190 (%0.11) - after loading of cursor resources
(5.1980) - Since Last Record = 0.0480 (%0.27) - after RegisterBuiltInCommands()
(5.4470) - Since Last Record = 0.2490 (%1.38) - Pre auto load plugins
(5.4990) - Since Last Record = 0.0520 (%0.29) - Toolbars
(5.5040) - Since Last Record = 0.0050 (%0.03) - RDK:CRhRdkRhinoPlugIn CTOR
(5.5060) - Since Last Record = 0.0020 (%0.01) - RDK:OnLoadPlugIn
(5.5140) - Since Last Record = 0.0080 (%0.04) - RDK:Initialize: start
(5.5140) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(5.5280) - Since Last Record = 0.0140 (%0.08) - RDK:Initialize: after default post effect plug-in factories
(5.5280) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(5.5280) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(5.5480) - Since Last Record = 0.0200 (%0.11) - RDK:Initialize: end
(5.5480) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(5.5480) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(5.5560) - Since Last Record = 0.0080 (%0.04) - RDK:OnLoadPlugIn - end
(5.6810) - Since Last Record = 0.1250 (%0.69) - Renderer Development Kit
(5.9710) - Since Last Record = 0.2900 (%1.61) - Commands
(6.0130) - Since Last Record = 0.0420 (%0.23) - Snapshots
(6.0540) - Since Last Record = 0.0410 (%0.23) - Rhino Render
(6.1930) - Since Last Record = 0.1390 (%0.77) - RDK_EtoUI
(6.2520) - Since Last Record = 0.0590 (%0.33) - MeshCommands
(6.5470) - Since Last Record = 0.2950 (%1.64) - RhinoCycles
(6.6040) - Since Last Record = 0.0570 (%0.32) - 3Dconnexion 3D Mouse
(6.6480) - Since Last Record = 0.0440 (%0.24) - Displacement
(6.7280) - Since Last Record = 0.0800 (%0.44) - SectionTools
(7.2580) - Since Last Record = 0.5300 (%2.94) - Datasmith Exporter
(7.2900) - Since Last Record = 0.0320 (%0.18) - BlockEdit
(7.3050) - Since Last Record = 0.0150 (%0.08) - RhinoPbrMaterial
(7.3280) - Since Last Record = 0.0230 (%0.13) - SubstanceImporter
(7.3280) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(7.3280) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(7.3280) - Since Last Record = 0.0000 (%0.00) - after create TabbedDockBarCollection
(7.3290) - Since Last Record = 0.0010 (%0.01) - after load default render engine
(7.3450) - Since Last Record = 0.0160 (%0.09) - after CloseAllDocuments
(7.4490) - Since Last Record = 0.1040 (%0.58) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(7.4500) - Since Last Record = 0.0010 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(8.2980) - Since Last Record = 0.8480 (%4.71) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(8.2980) - Since Last Record = 0.0000 (%0.00) - after m_pCallbacks->LoadToolBarFiles
(8.2980) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(9.1190) - Since Last Record = 0.8210 (%4.56) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(9.1190) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(9.1210) - Since Last Record = 0.0020 (%0.01) - before Process Shell Commands
(17.6140) - Since Last Record = 8.4930 (%47.18) - AnimationTools
(17.9890) - Since Last Record = 0.3750 (%2.08) - after Process Shell Commands
(18.0010) - Since Last Record = 0.0120 (%0.07) - CRhStopWatch::ShowReport()
Command: _CommandHistory
Command: _Options
Command: _CommandHistory

Sooo…Now it’s AnimationTools that’s over 8sec. Which mean the culprit is…Process Shell Commands!! I have no idea what I do with that information

So I disable AnimationTools, and now the “after Process Shell Commands” is 9secs.

Disabled AnimationTools

Read 2 annotation styles that reference 1 font that is not installed on this device.
Annotation styles referencing a missing font:
AMF_1/4" (48 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
AMF_1/2" (24 scale) / Metrostyle Extended [MetrostyleExtended-Regular]
Missing font:
Metrostyle Extended [MetrostyleExtended-Regular]
(0.0000) - Since Last Record = 0.0000 (%0.00) - Create Stopwatch
(1.1820) - Since Last Record = 1.1820 (%6.67) - after create and load AppSettings
(1.1840) - Since Last Record = 0.0020 (%0.01) - before CRhWGLExtensions::InitWGLExtensions()
(1.5450) - Since Last Record = 0.3610 (%2.04) - after CRhWGLExtensions::InitWGLExtensions()
(1.5500) - Since Last Record = 0.0050 (%0.03) - before Create splash window
(1.5510) - Since Last Record = 0.0010 (%0.01) - after Create splash window
(1.5550) - Since Last Record = 0.0040 (%0.02) - construct CMainFrame
(2.5500) - Since Last Record = 0.9950 (%5.62) - after load CMainFrame
(2.8560) - Since Last Record = 0.3060 (%1.73) - after initialize license manager
(4.9290) - Since Last Record = 2.0730 (%11.70) - after log events
(4.9330) - Since Last Record = 0.0040 (%0.02) - after registering shell file types
(4.9330) - Since Last Record = 0.0000 (%0.00) - before loading of cursor resources
(4.9530) - Since Last Record = 0.0200 (%0.11) - after loading of cursor resources
(5.0470) - Since Last Record = 0.0940 (%0.53) - after RegisterBuiltInCommands()
(5.1220) - Since Last Record = 0.0750 (%0.42) - Pre auto load plugins
(5.1750) - Since Last Record = 0.0530 (%0.30) - Toolbars
(5.1840) - Since Last Record = 0.0090 (%0.05) - RDK:CRhRdkRhinoPlugIn CTOR
(5.1860) - Since Last Record = 0.0020 (%0.01) - RDK:OnLoadPlugIn
(5.1970) - Since Last Record = 0.0110 (%0.06) - RDK:Initialize: start
(5.1970) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after default registered properties
(5.2110) - Since Last Record = 0.0140 (%0.08) - RDK:Initialize: after default post effect plug-in factories
(5.2110) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after adding internal extensions
(5.2110) - Since Last Record = 0.0000 (%0.00) - RDK:Initialize: after conduits
(5.2270) - Since Last Record = 0.0160 (%0.09) - RDK:Initialize: end
(5.2270) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK Initialize
(5.2270) - Since Last Record = 0.0000 (%0.00) - RDK:OnLoadPlugIn: after RDK OnLoadRhinoPlugIn
(5.2310) - Since Last Record = 0.0040 (%0.02) - RDK:OnLoadPlugIn - end
(5.3420) - Since Last Record = 0.1110 (%0.63) - Renderer Development Kit
(5.6400) - Since Last Record = 0.2980 (%1.68) - Commands
(5.6900) - Since Last Record = 0.0500 (%0.28) - Snapshots
(5.7360) - Since Last Record = 0.0460 (%0.26) - Rhino Render
(5.8800) - Since Last Record = 0.1440 (%0.81) - RDK_EtoUI
(5.9380) - Since Last Record = 0.0580 (%0.33) - MeshCommands
(6.2120) - Since Last Record = 0.2740 (%1.55) - RhinoCycles
(6.2700) - Since Last Record = 0.0580 (%0.33) - 3Dconnexion 3D Mouse
(6.3140) - Since Last Record = 0.0440 (%0.25) - Displacement
(6.3720) - Since Last Record = 0.0580 (%0.33) - SectionTools
(6.8510) - Since Last Record = 0.4790 (%2.70) - Datasmith Exporter
(6.8880) - Since Last Record = 0.0370 (%0.21) - BlockEdit
(6.9030) - Since Last Record = 0.0150 (%0.08) - RhinoPbrMaterial
(6.9240) - Since Last Record = 0.0210 (%0.12) - SubstanceImporter
(6.9240) - Since Last Record = 0.0000 (%0.00) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.9240) - Since Last Record = 0.0000 (%0.00) - End of C# Rhino.UI.TabPanels.NamedCallbacks.RestoreTabPanelCollections
(6.9250) - Since Last Record = 0.0010 (%0.01) - after create TabbedDockBarCollection
(6.9260) - Since Last Record = 0.0010 (%0.01) - after load default render engine
(6.9280) - Since Last Record = 0.0020 (%0.01) - after CloseAllDocuments
(7.0150) - Since Last Record = 0.0870 (%0.49) - before LoadToolBarsAndRestoreDockBarPositionAndStates
(7.0170) - Since Last Record = 0.0020 (%0.01) - Start of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.7430) - Since Last Record = 0.7260 (%4.10) - End of C# Rhino.UI.TabPanels.NamedCallbacks.LoadInitialToolBars
(7.7430) - Since Last Record = 0.0000 (%0.00) - after m_pCallbacks->LoadToolBarFiles
(7.7430) - Since Last Record = 0.0000 (%0.00) - after RunLoadToolbars
(8.6240) - Since Last Record = 0.8810 (%4.97) - after RunPostLoadToolBarsAndRestoreDockBarPositionAndStates
(8.6240) - Since Last Record = 0.0000 (%0.00) - after LoadToolBarsAndRestoreDockBarPositionAndStates
(8.6260) - Since Last Record = 0.0020 (%0.01) - before Process Shell Commands
(17.6990) - Since Last Record = 9.0730 (%51.22) - after Process Shell Commands
(17.7140) - Since Last Record = 0.0150 (%0.08) - CRhStopWatch::ShowReport()
Command: _CommandHistory

So no matter what I take out, whatever fills that certain slot takes around 9secs.

Anybody have any ideas?
@brian @wim

Link to Template File

And I’ve uploaded a video of Rhino 8 opening on the support page.

Thx
Alan

nathanletwory · May 31, 2024, 3:59am

Thanks for uploading the video. It appears that you are experiencing an unresponsive GUI after Rhino has started as evidenced by the stopwatch results showing.

I’m not sure what is going on there, maybe @curtisw has a better idea there.

curtisw · May 31, 2024, 4:30pm

I can certainly replicate the issue with that template, and for some reason it’s taking all the time reading the bitmap table from the 3dm, and a good portion of that trying to find files…

Alan_Farkas · May 31, 2024, 4:30pm

Thanks, Nathan. I’m all ears…

Alan_Farkas · May 31, 2024, 4:48pm

I have a feeling this is stemming from my Toolbar woes:

I was actually in the middle of rebuilding my toolbars from the ground up in the Default RUI (or whatever we’re calling it) when I lost everything, then had to quickly get up and running for the day, so I brought my old Toolbar back in from a separate RUI. Hope this helps the process of figuring this out.

Adding @theoutside to the thread.

Thx

Alan

jeff · May 31, 2024, 5:43pm

Hi @Alan_Farkas,

Your template file contains many (63) “bitmap table entries”, which reference folders on your local disks (C:, D:, E:, G:, and Z:), and some contain paths to other user account folders on your disk, as well as folders on some network drive/machine (the name I will leave out), this can compound things when Rhino is loading a .3dm file…

When Rhino encounters a “bitmap table entry”, it first tries to locate the file on your disk based on where the .3dm file was loaded (note: It does a full sub-folder search), if that fails, it then takes a look at the “referenced” location of the bitmap (the place where the file was originally loaded when placed in the document), if that fails, then it finally uses the version found in the bitmap table.

So, depending on where the 3dm file is loaded, and whether or not the bitmap files exist on disk, this can be fast or it can take some time, especially if the referenced files are on some network drive.

The first thing I would try is to place the template file in a folder that has no sub-folders, that will at least prevent Rhino from doing unnecessary searches in other folders. But I would really go through and make sure that all of the bitmap table entries are things you want, use, and exist somewhere on your local drive (and account), otherwise, searches can take an undetermined amount of time.

That’s my $0.02

-Jeff

Alan_Farkas · June 11, 2024, 8:14pm

Thanks, @jeff

I did have an “_archive” folder in the native Template Files folder. I’ve deleted it.

As far as bitmaps, is there a default location where Bitmaps should be wrangled?
Any “Consolidate Bitmaps” script or command?

Thx

Topic		Replies	Views
Rhino slow to open Rhino windows	7	125	March 3, 2025
Latest Service Release made Rhino 8 incredibly slow Rhino for Windows windows	19	364	July 25, 2024
Crashing on startup Rhino for Windows windows	7	55	November 15, 2024
Rhino 8 SR8 Release Candidate 1 (8.8.24135) CRASHES after startup Rhino windows	38	588	June 18, 2024
Need to prove my boss wrong Rhino windows , rhino8	5	84	August 2, 2024

RhinoStart: Initial startup...(RH-79171 1) - Not Fixed

Related topics