Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[BUG] Cura 5.7.0 OOMs shortly after container starts #8

Open
1 task done
roobre opened this issue Apr 16, 2024 · 18 comments
Open
1 task done

[BUG] Cura 5.7.0 OOMs shortly after container starts #8

roobre opened this issue Apr 16, 2024 · 18 comments
Assignees
Labels
work-in-progress Stale exempt

Comments

@roobre
Copy link

roobre commented Apr 16, 2024

Is there an existing issue for this?

  • I have searched the existing issues

Current Behavior

After starting the container, on both ghcr.io/linuxserver/cura:5.7.0-ls20 and ghcr.io/linuxserver/cura:5.7.0-ls19 the CuraEngine process starts allocating memory unbounded until it eventually gets OOMKilled by the kernel.
image

[761201.602155] Out of memory: Killed process 380256 (CuraEngine) total-vm:50362448kB, anon-rss:22355824kB, file-rss:512kB, shmem-rss:0kB, UID:911 pgtables:50408kB oom_score_adj:0
[761201.607097] warn_alloc: 244261 callbacks suppressed
[761201.607100] kswapd0: page allocation failure: order:0, mode:0x820(GFP_ATOMIC), nodemask=(null),cpuset=/,mems_allowed=0
[761201.607106] CPU: 5 PID: 137 Comm: kswapd0 Tainted: P           OE      6.8.4-arch1-1 #1 7ea0d8fced45b5f098eb034690645970f116c34c

This did not happen with 5.6.0-ls18.

Expected Behavior

CuraEngine should not die.

Steps To Reproduce

  1. docker run --rm -i ghcr.io/linuxserver/cura:5.7.0-ls20
  2. Monitor dmesg (OOMKill does not show up in docker, as this is not the main process of the container)

Environment

- OS: Arch linux 6.8.4-arch1-1, 6.6.20-1-lts
- How docker service was installed: Distro package manager

CPU architecture

x86-64

Docker creation

`docker run --rm -i ghcr.io/linuxserver/cura:5.7.0-ls20`.

Noticed originally in Kubernetes, reproduced in Docker.

Container logs

14:45:06 ~ $> dr ghcr.io/linuxserver/cura:5.7.0-ls20
[migrations] started
[migrations] no migrations found
usermod: no changes
───────────────────────────────────────

      ██╗     ███████╗██╗ ██████╗
      ██║     ██╔════╝██║██╔═══██╗
      ██║     ███████╗██║██║   ██║
      ██║     ╚════██║██║██║   ██║
      ███████╗███████║██║╚██████╔╝
      ╚══════╝╚══════╝╚═╝ ╚═════╝

   Brought to you by linuxserver.io
───────────────────────────────────────

To support LSIO projects visit:
https://www.linuxserver.io/donate/

───────────────────────────────────────
GID/UID
───────────────────────────────────────

User UID:    911
User GID:    911
───────────────────────────────────────

..............+...+...........+.........+.+......+..............+.......+.....+..........+..+.+.........+.....+......+.........+.+..+...+.......+..+.+......+.....+.+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++*.....+....+.........+........+.......+..+.+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++*..+.+...............+........+...+.......+...............+......+..+.......+......+.....+.+...+.................+.+..+............+...+.+.........+.....................+.....+.......+.....+.......+......+..+...+.+........+...+...+.+...+..+.........+.+.........+..+...+......+.............+..+......+...+.........+.+..............+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
.....+.......+..+......+.+......+.....+...+..........+..+.......+..+...+.+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++*...........+...+......+.+...+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++*.+.....+....+..+.......+............+.........+...........+....+......+...+..+.........+.+.........+..+...+.+.........+...............+.....+.+.....+.+.....+...+...................+...+..+.+..+.............+......+...+...+............+.....+.............+.....+...+.+......+........+.......+.................+.+..+.........+......+...+.+...+...............+.................+.+..+............+.+.....+..........+...+..+.............+...........+...+...+............+.......+...+.....+......+.........+.+..+...+.+...+........+....+.....+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
-----
[custom-init] No custom files found, skipping...
[ls.io-init] done.
_XSERVTransmkdir: ERROR: euid != 0,directory /tmp/.X11-unix will not be created.

Xvnc KasmVNC 1.2.0 - built Apr  6 2024 18:56:15
Copyright (C) 1999-2018 KasmVNC Team and many others (see README.me)
See http://kasmweb.com for information on KasmVNC.
Underlying X server release 12014000, The X.Org Foundation

Obt-Message: Xinerama extension is not present on the server

(UltiMaker-Cura:292): dbind-WARNING **: 12:45:10.922: AT-SPI: Error retrieving accessibility bus address: org.freedesktop.DBus.Error.ServiceUnknown: The name org.a11y.Bus was not provided by any .service files
qt.qml.typeresolution.cycle: Cyclic dependency detected between "file:///opt/cura/share/cura/resources/qml/Actions.qml" and "file:///opt/cura/share/cura/resources/qml/Actions.qml"
^C^C^C^C
Copy link

Thanks for opening your first issue here! Be sure to follow the relevant issue templates, or risk having this issue marked as invalid.

@j0nnymoe
Copy link
Member

Are you able to post the logs from cura itself to see if it's kicking up any errors about this?

@j0nnymoe
Copy link
Member

Fwiw, OOMs aren't uncommon with Cura https://github.com/Ultimaker/Cura/issues?q=memory+leak

@roobre
Copy link
Author

roobre commented Apr 16, 2024

I gathered the logs for cura, but I cannot spot anything obvious from it 🤔

Fwiw, OOMs aren't uncommon with Cura https://github.com/Ultimaker/Cura/issues?q=memory+leak

Yep, I thought the same when trying to look for a report upstream. However I did notice that many of those reports involved specific situations: A particular model, a particular action (e.g. slicing or moving the model around, etc.). I wasn't able to find an issue where cura would crash before any user action.

17:16:11 ~ $> docker exec -ti $(docker ps | grep cura | awk '{print $1}') /bin/bash
root@6e9bb2311244:/# cat ~/.local/share/cura/stderr.log
trimesh/curvature.py:12: DeprecationWarning: Please use `coo_matrix` from the `scipy.sparse` namespace, the `scipy.sparse.coo` namespace is deprecated.
  from scipy.sparse.coo import coo_matrix
UM/Qt/QtApplication.py:701: DeprecationWarning: QEvent constructor is deprecated
  super().__init__(self.QtFunctionEvent)
2024-04-16 15:15:32,395 - DEBUG - [MainThread] UM.Application.initialize [163]: Initializing UltiMaker Cura
2024-04-16 15:15:32,395 - DEBUG - [MainThread] UM.Application.initialize [164]: App Version 5.7.0
2024-04-16 15:15:32,395 - DEBUG - [MainThread] UM.Application.initialize [165]: Api Version 8.7.0
2024-04-16 15:15:32,395 - DEBUG - [MainThread] UM.Application.initialize [166]: Build type None
2024-04-16 15:15:32,395 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/cura/../Resources/share/uranium/plugins must be a folder.
2024-04-16 15:15:32,395 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/cura/../Resources/share/cura/plugins must be a folder.
2024-04-16 15:15:32,395 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib/uranium must be a folder.
2024-04-16 15:15:32,395 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib64/uranium must be a folder.
2024-04-16 15:15:32,396 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib32/uranium must be a folder.
2024-04-16 15:15:32,396 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/cura/plugins must be a folder.
2024-04-16 15:15:32,396 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/Resources/uranium/plugins must be a folder.
2024-04-16 15:15:32,396 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/Resources/cura/plugins must be a folder.
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources.__initializeStoragePaths [481]: Initializing storage paths
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources.__initializeStoragePaths [491]: Config storage path is /config/.config/cura/5.7
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources.__initializeStoragePaths [499]: Data storage path is /config/.local/share/cura/5.7
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources.__initializeStoragePaths [511]: Cache storage path is /config/.cache/cura/5.7
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources._copyLatestDirsIfPresent [536]: Found config: ['/config/.config/cura'] and data: ['/config/.local/share/cura']
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.Resources._copyLatestDirsIfPresent [540]: Latest config path: None and latest data path: None
2024-04-16 15:15:32,396 - INFO - [MainThread] UM.Qt.QtApplication.initialize [164]: Preferences file not found, ignore and use default language 'en_US'
2024-04-16 15:15:32,396 - INFO - [MainThread] UM.PackageManager.__init__ [56]: Found bundled packages JSON file: /opt/cura/share/cura/resources/bundled_packages/bundled_CuraEngineGradualFlow.json
2024-04-16 15:15:32,396 - INFO - [MainThread] UM.PackageManager.__init__ [56]: Found bundled packages JSON file: /opt/cura/share/cura/resources/bundled_packages/cura.json
2024-04-16 15:15:32,396 - INFO - [MainThread] UM.PackageManager.__init__ [56]: Found bundled packages JSON file: /opt/cura/share/uranium/resources/bundled_packages/uranium.json
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.View.GL.OpenGLContext.detectBestOpenGLVersion [124]: Trying OpenGL context 4.1...
2024-04-16 15:15:32,396 - DEBUG - [MainThread] UM.View.GL.OpenGLContext.detectBestOpenGLVersion [132]: Yay, we got at least OpenGL 4.1 core: 4.5 Core profile
2024-04-16 15:15:32,397 - DEBUG - [MainThread] UM.Qt.QtApplication.initialize [192]: Detected most suitable OpenGL context version: 4.1 Core profile
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.Qt.QtApplication.initialize [199]: Initializing job queue ...
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.Qt.QtApplication.initialize [203]: Initializing version upgrade manager ...
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PackageManager._loadManagementData [174]: Loaded bundled packages data from /opt/cura/share/cura/resources/bundled_packages/bundled_CuraEngineGradualFlow.json
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PackageManager._loadManagementData [174]: Loaded bundled packages data from /opt/cura/share/cura/resources/bundled_packages/cura.json
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PackageManager._loadManagementData [174]: Loaded bundled packages data from /opt/cura/share/uranium/resources/bundled_packages/uranium.json
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PackageManager._loadManagementData [203]: User package management file /config/.local/share/cura/5.7/packages.json doesn't exist, do nothing
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PackageManager._removeAllScheduledPackages [280]: Attempting to remove the following scheduled packages: -
2024-04-16 15:15:32,397 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib/cura must be a folder.
2024-04-16 15:15:32,397 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib64/cura must be a folder.
2024-04-16 15:15:32,397 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/lib32/cura must be a folder.
2024-04-16 15:15:32,397 - WARNING - [MainThread] UM.PluginRegistry.addPluginLocation [205]: Plugin location /opt/libx32/cura must be a folder.
2024-04-16 15:15:32,397 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin ConsoleLogger 1.0.1
2024-04-16 15:15:32,401 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin LocalContainerProvider 1.0.1
2024-04-16 15:15:32,403 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin PrepareStage 1.0.1
2024-04-16 15:15:32,406 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin MonitorStage 1.0.1
2024-04-16 15:15:32,415 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin XmlMaterialProfile 1.0.1
2024-04-16 15:15:32,421 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin UFPWriter 1.0.1
2024-04-16 15:15:32,433 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin SimulationView 1.0.1
/opt/cura/share/cura/plugins/UM3NetworkPrinting/src/ExportFileJob.py:31: DeprecationWarning: invalid escape sequence '\w'
  job_name = re.sub("[^\w\-. ()]", "-", job_name)
2024-04-16 15:15:32,479 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin UM3NetworkPrinting 2.0.0
2024-04-16 15:15:32,482 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin CuraProfileReader 1.0.1
2024-04-16 15:15:32,484 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin GCodeGzReader 1.0.1
2024-04-16 15:15:32,486 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin LegacyProfileReader 1.0.1
2024-04-16 15:15:32,505 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin DigitalLibrary 1.1.0
2024-04-16 15:15:32,532 - DEBUG - [MainThread] 3MFReader.SpecificSettingsModel._update [59]: Updating SpecificSettingsModel
2024-04-16 15:15:32,533 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin 3MFReader 1.0.1
2024-04-16 15:15:32,537 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin RemovableDriveOutputDevice 1.0.1
2024-04-16 15:15:32,554 - DEBUG - [MainThread] cura.OAuth2.AuthorizationService.getAccessToken [158]: No auth data to retrieve the access_token from
2024-04-16 15:15:32,555 - DEBUG - [MainThread] cura.UltimakerCloud.UltimakerCloudScope.requestHook [30]: User is not logged in for Cloud API request to https://api.ultimaker.com/cura-packages/v1/cura/v8.7.0/packages/package-updates?installed_packages=VersionUpgrade30to31:1.0.1&installed_packages=STLWriter:1.0.1&installed_packages=ScaleTool:1.0.1&installed_packages=3MFReader:1.0.1&installed_packages=VersionUpgrade22to24:1.0.1&installed_packages=GCodeGzWriter:1.0.1&installed_packages=FirmwareUpdater:1.0.1&installed_packages=VersionUpgrade32to33:1.0.1&installed_packages=VersionUpgrade413to50:1.0.0&installed_packages=VersionUpgrade47to48:1.0.0&installed_packages=LegacyProfileReader:1.0.1&installed_packages=CuraProfileReader:1.0.1&installed_packages=ModelChecker:1.0.1&installed_packages=MirrorTool:1.0.1&installed_packages=UFPWriter:1.0.1&installed_packages=CuraDrive:1.2.0&installed_packages=VersionUpgrade34to35:1.0.1&installed_packages=GCodeReader:1.0.1&installed_packages=VersionUpgrade411to412:1.0.0&installed_packages=SimulationView:1.0.1&installed_packages=FirmwareUpdateChecker:1.0.1&installed_packages=TrimeshReader:1.0.0&installed_packages=CuraEngineGradualFlow:0.1.0&installed_packages=VersionUpgrade48to49:1.0.0&installed_packages=AMFReader:1.0.0&installed_packages=VersionUpgrade25to26:1.0.1&installed_packages=VersionUpgrade460to462:1.0.0&installed_packages=VersionUpgrade35to40:1.0.0&installed_packages=VersionUpgrade26to27:1.0.1&installed_packages=OBJReader:1.0.1&installed_packages=UpdateChecker:1.0.1&installed_packages=DigitalLibrary:1.1.0&installed_packages=MakerbotWriter:1.0.1&installed_packages=VersionUpgrade52to53:1.0.0&installed_packages=USBPrinting:1.0.2&installed_packages=PostProcessingPlugin:2.2.1&installed_packages=SupportEraser:1.0.1&installed_packages=VersionUpgrade462to47:1.0.0&installed_packages=PerObjectSettingsTool:1.0.1&installed_packages=VersionUpgrade33to34:1.0.1&installed_packages=VersionUpgrade44to45:1.0.0&installed_packages=MachineSettingsAction:1.0.1&installed_packages=RemovableDriveOutputDevice:1.0.1&installed_packages=GCodeProfileReader:1.0.1&installed_packages=VersionUpgrade45to46:1.0.0&installed_packages=CuraProfileWriter:1.0.1&installed_packages=UM3NetworkPrinting:1.0.1&installed_packages=RotateTool:1.0.1&installed_packages=VersionUpgrade43to44:1.0.0&installed_packages=XRayView:1.0.1&installed_packages=UFPReader:1.0.0&installed_packages=VersionUpgrade40to41:1.0.1&installed_packages=VersionUpgrade49to410:1.0.0&installed_packages=VersionUpgrade27to30:1.0.1&installed_packages=ImageReader:1.0.1&installed_packages=X3DReader:1.0.1&installed_packages=VersionUpgrade21to22:1.0.1&installed_packages=OBJWriter:1.0.1&installed_packages=UltimakerMachineActions:1.0.1&installed_packages=SliceInfoPlugin:1.0.1&installed_packages=VersionUpgrade41to42:1.0.0&installed_packages=GCodeGzReader:1.0.1&installed_packages=SentryLogger:1.0.0&installed_packages=VersionUpgrade42to43:1.0.0&installed_packages=UltimakerPP:1.4.0&installed_packages=GenericPETCF:1.4.0&installed_packages=VertexDeltaABS:1.4.0&installed_packages=PolyPlusPLA:1.0.1&installed_packages=GenericCPEPlus:1.4.0&installed_packages=UltimakerNylon:1.4.0&installed_packages=GenericBAM:1.4.0&installed_packages=UltimakerTPU:1.4.0&installed_packages=GenericABS:1.4.0&installed_packages=IMADE3DJellyBOXPLA:1.0.1&installed_packages=UltimakerTPLA:1.4.0&installed_packages=UltimakerPETCF:1.4.0&installed_packages=GenericPVA:1.4.0&installed_packages=UltimakerCPE:1.4.0&installed_packages=GenericPP:1.4.0&installed_packages=ULTIMAKERABSRMETHOD:2.0.0&installed_packages=OctofiberPLA:1.0.1&installed_packages=UltimakerPC:1.4.0&installed_packages=FABtotumTPU:1.4.0&installed_packages=GenericToughPLA:1.4.0&installed_packages=GenericHIPS:1.4.0&installed_packages=GenericTPU:1.4.0&installed_packages=GenericCPE:1.4.0&installed_packages=GenericGFFPA:1.4.0&installed_packages=GenericNylon:1.4.0&installed_packages=UltimakerPETG:1.4.0&installed_packages=PolyWoodPLA:1.0.1&installed_packages=UltimakerCPEP:1.4.0&installed_packages=GenericPETG:1.4.0&installed_packages=FABtotumPLA:1.4.0&installed_packages=ULTIMAKERBASCFMETHOD:2.0.0&installed_packages=UltimakerPVA:1.4.0&installed_packages=VertexDeltaPLA:1.4.0&installed_packages=Filo3DPLA:1.0.1&installed_packages=ULTIMAKERNYLON12CFMETHOD:2.0.0&installed_packages=DagomaChromatikPLA:1.0.1&installed_packages=ULTIMAKERSR30METHOD:2.0.0&installed_packages=FABtotumNylon:1.4.0&installed_packages=ULTIMAKERASAMETHOD:2.0.0&installed_packages=FABtotumABS:1.4.0&installed_packages=PolyMaxPLA:1.0.1&installed_packages=VertexDeltaTPU:1.4.0&installed_packages=UltimakerPLA:1.4.0&installed_packages=VertexDeltaPET:1.4.0&installed_packages=ULTIMAKERRAPIDRINSEMETHOD:2.0.0&installed_packages=GenericGFFCPE:1.4.0&installed_packages=UltimakerABS:1.4.0&installed_packages=IMADE3DJellyBOXPETG:1.0.1&installed_packages=GenericPLA:1.4.0&installed_packages=GenericPC:1.4.0&installed_packages=GenericCFFPA:1.4.0&installed_packages=GenericCFFCPE:1.4.0&installed_packages=UltimakerBAM:1.4.0&installed_packages=FiberlogyHDPLA:1.0.1&installed_packages=PolyFlexPLA:1.0.1
2024-04-16 15:15:32,555 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin Marketplace 1.0.0
2024-04-16 15:15:32,560 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin SolidView 1.0.1
2024-04-16 15:15:32,567 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin GCodeReader 1.0.1
2024-04-16 15:15:32,570 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin SliceInfoPlugin 1.0.1
2024-04-16 15:15:32,573 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade54to55 1.0.0
2024-04-16 15:15:32,576 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade48to49 1.0.0
2024-04-16 15:15:32,582 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade21to22 1.0.1
2024-04-16 15:15:32,584 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade33to34 1.0.1
2024-04-16 15:15:32,586 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade35to40 1.0.0
2024-04-16 15:15:32,589 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade460to462 1.0.0
2024-04-16 15:15:32,591 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade30to31 1.0.1
2024-04-16 15:15:32,594 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade45to46 1.0.0
2024-04-16 15:15:32,597 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade44to45 1.0.0
2024-04-16 15:15:32,599 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade56to57 1.0.0
2024-04-16 15:15:32,602 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade22to24 1.0.1
2024-04-16 15:15:32,604 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade32to33 1.0.1
2024-04-16 15:15:32,607 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade413to50 1.0.0
2024-04-16 15:15:32,609 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade43to44 1.0.0
2024-04-16 15:15:32,612 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade25to26 1.0.1
2024-04-16 15:15:32,614 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade49to410 1.0.0
2024-04-16 15:15:32,617 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade411to412 1.0.0
2024-04-16 15:15:32,619 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade40to41 1.0.1
2024-04-16 15:15:32,621 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade53to54 1.0.0
2024-04-16 15:15:32,624 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade41to42 1.0.0
2024-04-16 15:15:32,627 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade34to35 1.0.1
2024-04-16 15:15:32,629 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade26to27 1.0.1
2024-04-16 15:15:32,632 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade52to53 1.0.0
2024-04-16 15:15:32,634 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade42to43 1.0.0
2024-04-16 15:15:32,637 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade47to48 1.0.0
2024-04-16 15:15:32,640 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade462to47 1.0.0
2024-04-16 15:15:32,642 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin VersionUpgrade27to30 1.0.1
2024-04-16 15:15:32,644 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin GCodeProfileReader 1.0.1
2024-04-16 15:15:32,647 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin FirmwareUpdater 1.0.1
2024-04-16 15:15:32,654 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin X3DReader 1.0.1
2024-04-16 15:15:32,656 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin ModelChecker 1.0.1
2024-04-16 15:15:32,660 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin PostProcessingPlugin 2.2.1
2024-04-16 15:15:32,663 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin FirmwareUpdateChecker 1.0.1
2024-04-16 15:15:32,682 - INFO - [MainThread] CuraEngineBackend.CuraEngineBackend.__init__ [109]: Found CuraEngine at: /opt/cura/CuraEngine
2024-04-16 15:15:32,682 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin CuraEngineBackend 1.0.1
2024-04-16 15:15:32,686 - DEBUG - [MainThread] UM.Backend.Backend._logSocketState [232]: Socket state changed to Listening
2024-04-16 15:15:32,689 - INFO - [MainThread] UM.Backend.Backend.startEngine [96]: Started engine process: /opt/cura/CuraEngine
2024-04-16 15:15:32,690 - DEBUG - [MainThread] UM.Backend.Backend._backendLog [140]: [Backend] Calling engine with: ['/opt/cura/CuraEngine', 'connect', '127.0.0.1:49674', '']
2024-04-16 15:15:32,692 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin XRayView 1.0.1
2024-04-16 15:15:32,694 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin PreviewStage 1.0.1
2024-04-16 15:15:32,698 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin PerObjectSettingsTool 1.0.1
2024-04-16 15:15:32,700 - DEBUG - [EngineOutputThread] UM.Backend.Backend._backendLog [140]: [Backend] [2024-04-16 15:15:32.700] [info] Sentry url: https://734f9ec9024f73e53701d59c3ffddfe3@o323038.ingest.sentry.io/4506257745510401
2024-04-16 15:15:32,701 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin GCodeWriter 1.0.1
2024-04-16 15:15:32,709 - INFO - [MainThread] MakerbotWriter.MakerbotWriter.__init__ [34]: Using PyDulcificum: 0.2.0-alpha.0
2024-04-16 15:15:32,709 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin MakerbotWriter 0.1.0
/opt/cura/share/cura/plugins/USBPrinting/USBPrinterOutputDevice.py:280: DeprecationWarning: invalid escape sequence '\d'
  if re.search(b"[B|T\d*]: ?\d+\.?\d*", line):  # Temperature message. 'T:' for extruder and 'B:' for bed
2024-04-16 15:15:32,720 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin USBPrinting 1.0.2
2024-04-16 15:15:32,723 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin MachineSettingsAction 1.0.1
2024-04-16 15:15:32,727 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin CuraEngineGradualFlow 0.1.0
2024-04-16 15:15:32,729 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin CuraProfileWriter 1.0.1
2024-04-16 15:15:32,735 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin CuraDrive 1.2.0
2024-04-16 15:15:32,737 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin GCodeGzWriter 1.0.1
2024-04-16 15:15:32,740 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin AMFReader 1.0.0
2024-04-16 15:15:32,749 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin 3MFWriter 1.0.1
2024-04-16 15:15:32,753 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin ImageReader 1.0.1
2024-04-16 15:15:32,756 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin TrimeshReader 1.0.0
2024-04-16 15:15:32,758 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin UFPReader 1.0.0
2024-04-16 15:15:32,761 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin UltimakerMachineActions 1.0.1
2024-04-16 15:15:32,765 - INFO - [MainThread] UM.PluginRegistry.loadPlugin [540]: Loaded plugin SupportEraser 1.0.1
2024-04-16 15:15:32,766 - DEBUG - [MainThread] UM.PluginRegistry.loadPlugins [456]: Loading all plugins took 0.45014214515686035 seconds
2024-04-16 15:15:32,768 - INFO - [MainThread] UM.VersionUpgradeManager.upgrade [163]: Looking for old configuration files to upgrade.
2024-04-16 15:15:32,772 - INFO - [MainThread] UM.VersionUpgradeManager.upgrade [177]: Checking and performing updates took 0.004601716995239258
2024-04-16 15:15:32,773 - INFO - [MainThread] UM.Qt.QtApplication.startSplashWindowPhase [256]: The preferences file cannot be opened or it is corrupted, so we will use default values
2024-04-16 15:15:32,773 - INFO - [MainThread] UM.Qt.QtApplication.startSplashWindowPhase [263]: The preferences file 'None' cannot be found, will use default values
2024-04-16 15:15:32,774 - INFO - [MainThread] UM.Qt.QtApplication.startSplashWindowPhase [266]: Completed loading preferences.
2024-04-16 15:15:32,780 - INFO - [MainThread] UM.Qt.QtApplication.startSplashWindowPhase [304]: Created system tray icon.
2024-04-16 15:15:33,033 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/extruders/qt6.conf, ignoring it.
2024-04-16 15:15:33,078 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/definitions/qt6.conf, ignoring it.
2024-04-16 15:15:33,089 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/variants/renkforce/qt6.conf, ignoring it.
2024-04-16 15:15:33,107 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/quality/deltacomb/qt6.conf, ignoring it.
2024-04-16 15:15:33,110 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/definitions/qt.conf, ignoring it.
2024-04-16 15:15:33,112 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/extruders/qt.conf, ignoring it.
2024-04-16 15:15:33,115 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/quality/deltacomb/qt.conf, ignoring it.
2024-04-16 15:15:33,121 - WARNING - [MainThread] LocalContainerProvider.LocalContainerProvider._pathToIdAndMime [359]: MIME type could not be found for file: /opt/cura/share/cura/resources/variants/renkforce/qt.conf, ignoring it.
2024-04-16 15:15:34,251 - DEBUG - [MainThread] UM.Settings.ContainerRegistry.loadAllMetadata [565]: Loading metadata into container registry took 1.4478988647460938 seconds
2024-04-16 15:15:34,263 - DEBUG - [MainThread] cura.Scene.CuraSceneController.setActiveBuildPlate [142]: Selected build plate: 0
2024-04-16 15:15:34,264 - INFO - [MainThread] cura.CuraApplication._log_hardware_info [957]: System: Linux
2024-04-16 15:15:34,264 - INFO - [MainThread] cura.CuraApplication._log_hardware_info [958]: Release: 6.8.4-arch1-1
2024-04-16 15:15:34,265 - INFO - [MainThread] cura.CuraApplication._log_hardware_info [959]: Version: #1 SMP PREEMPT_DYNAMIC Fri, 05 Apr 2024 00:14:23 +0000
2024-04-16 15:15:34,270 - INFO - [MainThread] cura.CuraApplication._log_hardware_info [960]: Processor name:
2024-04-16 15:15:34,270 - INFO - [MainThread] cura.CuraApplication._log_hardware_info [961]: CPU Cores: 16
2024-04-16 15:15:34,270 - DEBUG - [MainThread] cura.CuraApplication.run [884]: Using conan dependencies: {'uranium': {'version': '5.7.0', 'revision': '7b89a9bf32f20c7fe5d89340cff6fa0d'}, 'curaengine': {'version': '5.7.0', 'revision': '6d2f5362cddaf4e431bef6f150fe9ec1'}, 'cura_binary_data': {'version': '5.7.0', 'revision': '18bb2779b7fb5e2f07b0c1d8c6452dca'}, 'fdm_materials': {'version': '5.7.0', 'revision': 'b670a143833a3c2eee266426b62e0af1'}, 'curaengine_plugin_gradual_flow': {'version': '0.1.0-beta.3', 'revision': '0b8dad39cac64fd328f49c92a43f7198'}, 'dulcificum': {'version': '0.2.0-alpha.0', 'revision': 'de0ad90aaf5e61e9cd12028d29d6a500'}, 'pysavitar': {'version': '5.3.0', 'revision': '2527fe9a8d353cf599bf8102c4a60cbc7b3a0d1c'}, 'pynest2d': {'version': '5.3.0', 'revision': 'a44ffd40f4ad593091a9066589c0a1cf240275d5'}, 'curaengine_grpc_definitions': {'version': '0.2.0', 'revision': '695a9e2aa8edc7b2b5ec8cbc721a33fd'}, 'cpython': {'version': '3.10.4', 'revision': 'cc63e814ae296e7ec9f538385326c283'}, 'clipper': {'version': '6.4.2', 'revision': 'c1833d66de687b42c707231e7c5486bd'}, 'openssl': {'version': '3.2.0', 'revision': '326bf1bafbdedf84af185ba5b77c972d'}, 'protobuf': {'version': '3.21.12', 'revision': 'ff413e7c6d81724e872661875af83e68'}, 'boost': {'version': '1.82.0', 'revision': 'aa17157205af595aaecc1a9d793cc1ae'}, 'spdlog': {'version': '1.12.0', 'revision': 'baf01ff8c9be09bb6cb6c62c1804012c'}, 'fmt': {'version': '10.1.1', 'revision': '0561f4fdf8146b5d82b926d7e0d9c66b'}, 'zlib': {'version': '1.2.13', 'revision': '4e74ebf1361fe6fb60326f473f276eb5'}, 'pyarcus': {'version': '5.3.1', 'revision': '0691865d9cf6f0779bd804967f7d2817739eaae4'}, 'scripta': {'version': '0.1.0', 'revision': '49f6bea1a1bed833bfdfe695525044d7'}, 'arcus': {'version': '5.3.1', 'revision': 'c4bd50a9393af0fe97acfd4db10ec2fc'}, 'sentry-native': {'version': '0.6.5', 'revision': 'a517c84ef70afa387c32a546ef5a14ad'}, 'neargye-semver': {'version': '0.3.0', 'revision': '799b2e371d5be985a073214e029ea6cd'}, 'asio-grpc': {'version': '2.6.0', 'revision': 'aa2b53a3bb7540e7a954e1fc0f51e7a3'}, 'grpc': {'version': '1.50.1', 'revision': '7a4f0512d0c0a016fc67652bb6d67d32'}, 'rapidjson': {'version': '1.1.0', 'revision': '9f5e39d1f4a6b67d48c6a343c4592f32'}, 'stb': {'version': '20200203', 'revision': 'cba8fa43f7a0ea5389896744664823c9'}, 'range-v3': {'version': '0.12.0', 'revision': '4c05d91d7b40e6b91b44b5345ac64408'}, 'mapbox-wagyu': {'version': '0.5.0', 'revision': 'e6cb299485b686893e07209fea6d5a0b'}, 'docopt.cpp': {'version': '0.6.3', 'revision': '7937c6a7f2f8bccd86f67d25837f9a39'}, 'ctre': {'version': '3.7.2', 'revision': '86bd3592feebcdafd2ab7b8a1aad0c80'}, 'nlohmann_json': {'version': '3.11.2', 'revision': '1ded6ae5d200a68ac17c51d528b945e2'}, 'pybind11': {'version': '2.10.4', 'revision': '969072b090ab0efbfe99670aca9360d0'}, 'savitar': {'version': '5.3.0', 'revision': 'e66e4e8f69e8764411d01b09140d04de8552552b'}, 'nest2d': {'version': '5.3.0', 'revision': '0e37a675ea212360daf2794541ad2c8b'}, 'expat': {'version': '2.4.1', 'revision': '742c681fc10b0ee1f11b490b483fa48e'}, 'libffi': {'version': '3.2.1', 'revision': '33b3eff20f1be5d569235ae100266729'}, 'mpdecimal': {'version': '2.5.0', 'revision': 'ef7f121607e10372c07a668830887b79'}, 'util-linux-libuuid': {'version': '2.39.2', 'revision': '339f80aea7e5cf507b5b9839a128909f'}, 'libxcrypt': {'version': '4.4.25', 'revision': '4f4f0f65221d0248e7246b973763b2ec'}, 'bzip2': {'version': '1.0.8', 'revision': '457c272f7da34cb9c67456dd217d36c4'}, 'gdbm': {'version': '1.19', 'revision': 'ce4a99f837ab0ce48fccbda9c8a7f945'}, 'sqlite3': {'version': '3.36.0', 'revision': '791ea1c6dc87facb455a40d2531aa088'}, 'tk': {'version': '8.6.10', 'revision': '1e8cbe0b5d8257de6bc6904da048766f'}, 'ncurses': {'version': '6.3', 'revision': '8e5fafd44cc8bfffde869c0a93432a7a'}, 'xz_utils': {'version': '5.2.5', 'revision': 'c99887b9646d3369581dfcf10e79cda5'}, 'libcurl': {'version': '8.6.0', 'revision': '2f5d0b0e684214641563d25d5ff32aa1'}, 'abseil': {'version': '20230125.3', 'revision': '207b0d386ea564ac506dfe34e4cd6b55'}, 'c-ares': {'version': '1.19.1', 'revision': '02ac853d7b96c7e631cc12bb373e6b42'}, 're2': {'version': '20230301', 'revision': '4482314b6488e5d71e9944f5bedb730d'}, 'mapbox-geometry': {'version': '2.0.3', 'revision': '1560d81c6cd8ea159ecbb748bf8b6090'}, 'pugixml': {'version': '1.12.1', 'revision': 'd0378a7e4e32d2d379c3a272269e4330'}, 'nlopt': {'version': '2.7.0', 'revision': '42f607e6bc9e04c81b75543d57511e8c'}, 'tcl': {'version': '8.6.10', 'revision': 'd694cc97f48640f0d66aa90b16cdc5cd'}, 'fontconfig': {'version': '2.13.93', 'revision': '5d592b22ecc0f9e5d0ef4d730501605c'}, 'xorg': {'version': 'system', 'revision': 'f626cbdd0ba57d9c53bce2d8b9362fad'}, 'mapbox-variant': {'version': '1.2.0', 'revision': 'e0533cc4c1625d48a0238760e6c85e35'}, 'freetype': {'version': '2.13.2', 'revision': 'dfa3d504cae4a08d5c72113bd6f28498'}, 'libpng': {'version': '1.6.43', 'revision': 'c219d8f01983bac10c404fc613605eef'}, 'brotli': {'version': '1.1.0', 'revision': 'd56d7bb9ca722942aba17369cb5c0519'}}
2024-04-16 15:15:34,271 - DEBUG - [MainThread] cura.CuraApplication.run [885]: Using python dependencies: {'pyinstaller-hooks-contrib': {'version': '2024.3'}, 'certifi': {'version': '2023.5.7'}, 'setuptools': {'version': '62.0.0'}, 'cryptography': {'version': '41.0.1'}, 'packaging': {'version': '24.0'}, 'keyring': {'version': '23.0.1'}, 'pip': {'version': '22.0.4'}, 'incremental': {'version': '21.3.0'}, 'attrs': {'version': '21.2.0'}, 'twisted': {'version': '21.2.0'}, 'hyperlink': {'version': '21.0.0'}, 'automat': {'version': '20.2.0'}, 'constantly': {'version': '15.1.0'}, 'pyqt6-sip': {'version': '13.6.0'}, 'py-cpuinfo': {'version': '9.0.0'}, 'pytest': {'version': '8.1.1'}, 'pyqt6-qt6': {'version': '6.6.0'}, 'pyqt6-networkauth-qt6': {'version': '6.6.0'}, 'colorlog': {'version': '6.6.0'}, 'pyqt6': {'version': '6.6.0'}, 'pyqt6-networkauth': {'version': '6.6.0'}, 'sip': {'version': '6.5.1'}, 'pyinstaller': {'version': '6.3.0'}, 'pyyaml': {'version': '6.0.1'}, 'zope.interface': {'version': '5.4.0'}, 'importlib-metadata': {'version': '4.10.0'}, 'pytest-benchmark': {'version': '4.0.0'}, 'typing-extensions': {'version': '3.10.0.2'}, 'trimesh': {'version': '3.9.36'}, 'zipp': {'version': '3.5.0'}, 'pyserial': {'version': '3.4'}, 'secretstorage': {'version': '3.3.3'}, 'jinja2': {'version': '3.1.3'}, 'chardet': {'version': '3.0.4'}, 'requests': {'version': '2.22.0'}, 'pycparser': {'version': '2.20'}, 'numpy-stl': {'version': '2.10.1'}, 'idna': {'version': '2.8'}, 'networkx': {'version': '2.6.2'}, 'pybind11': {'version': '2.6.2'}, 'python-utils': {'version': '2.3.0'}, 'markupsafe': {'version': '2.1.5'}, 'charset-normalizer': {'version': '2.1.0'}, 'shapely': {'version': '2.0.1'}, 'tomli': {'version': '2.0.1'}, 'iniconfig': {'version': '2.0.0'}, 'urllib3': {'version': '1.25.9'}, 'numpy': {'version': '1.21.5'}, 'cffi': {'version': '1.15.0'}, 'six': {'version': '1.12.0'}, 'scipy': {'version': '1.9.1'}, 'pluggy': {'version': '1.4.0'}, 'pyclipper': {'version': '1.3.0.post3'}, 'exceptiongroup': {'version': '1.2.0'}, 'charon': {'version': '1.0'}, 'mypy': {'version': '0.931'}, 'wheel': {'version': '0.37.1'}, 'zeroconf': {'version': '0.31.0'}, 'cython': {'version': '0.29.26'}, 'altgraph': {'version': '0.17.4'}, 'sentry-sdk': {'version': '0.13.5'}, 'toml': {'version': '0.10.2'}, 'jeepney': {'version': '0.7.1'}, 'mypy-extensions': {'version': '0.4.3'}, 'ifaddr': {'version': '0.1.7'}}
2024-04-16 15:15:34,271 - INFO - [MainThread] cura.CuraApplication.run [887]: Initializing machine error checker
UM/Qt/QtApplication.py:701: DeprecationWarning: QEvent constructor is deprecated
  super().__init__(self.QtFunctionEvent)
2024-04-16 15:15:34,272 - INFO - [MainThread] cura.CuraApplication.run [892]: Initializing machine manager
2024-04-16 15:15:34,272 - INFO - [MainThread] cura.CuraApplication.run [897]: Initializing container manager
2024-04-16 15:15:34,449 - WARNING - [MainThread] UM.Settings.SettingDefinition._deserialize_dict [668]: Unrecognised property comment in setting _plugin__curaenginegradualflow__0_1_0__max_flow_acceleration
2024-04-16 15:15:34,452 - DEBUG - [MainThread] cura.OAuth2.AuthorizationService.loadAuthDataFromPreferences [284]: Attempting to load the auth data from preferences.
2024-04-16 15:15:34,455 - WARNING - [MainThread] UM.OutputDevice.OutputDeviceManager.removeOutputDevice [235]: Could not find output device with id digital_factory to remove
Exception in thread Thread-1 (_updateThread):
Traceback (most recent call last):
  File "threading.py", line 1009, in _bootstrap_inner
  File "threading.py", line 946, in run
  File "/opt/cura/share/cura/plugins/RemovableDriveOutputDevice/RemovableDrivePlugin.py", line 59, in _updateThread
    result = self.checkRemovableDrives()
  File "/opt/cura/share/cura/plugins/RemovableDriveOutputDevice/LinuxRemovableDrivePlugin.py", line 31, in checkRemovableDrives
    for volume in glob.glob("/run/media/" + os.getenv("USER") + "/*"):
TypeError: can only concatenate str (not "NoneType") to str
2024-04-16 15:15:34,460 - DEBUG - [MainThread] UM.Scene.Camera._preferencesChanged [255]: Changing perspective mode to perspective.
2024-04-16 15:15:34,484 - DEBUG - [MainThread] UM.Qt.Bindings.Theme.load [188]: Loading theme file: /opt/cura/share/cura/resources/themes/cura-light/theme.json
2024-04-16 15:15:34,507 - DEBUG - [MainThread] UM.Qt.Bindings.Theme.load [323]: Loaded theme /opt/cura/share/cura/resources/themes/cura-light
2024-04-16 15:15:34,507 - INFO - [MainThread] UM.Qt.Bindings.Theme.load [324]: System's em size is 14px.
2024-04-16 15:15:35,751 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin._createView [355]: Creating post processing plugin view.
/opt/cura/share/cura/plugins/PostProcessingPlugin/Script.py:125: DeprecationWarning: invalid escape sequence '\.'
  m = re.search('^-?[0-9]+\.?[0-9]*', sub_part)
2024-04-16 15:15:35,757 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: AddCoolingProfile
2024-04-16 15:15:35,761 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: ChangeAtZ
2024-04-16 15:15:35,763 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: ColorMix
2024-04-16 15:15:35,764 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: CreateThumbnail
2024-04-16 15:15:35,764 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: DisplayFilenameAndLayerOnLCD
2024-04-16 15:15:35,767 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: DisplayInfoOnLCD
2024-04-16 15:15:35,767 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: DisplayProgressOnLCD
2024-04-16 15:15:35,768 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: FilamentChange
2024-04-16 15:15:35,769 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: InsertAtLayerChange
2024-04-16 15:15:35,771 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: LimitXYAccelJerk
/opt/cura/share/cura/plugins/PostProcessingPlugin/scripts/PauseAtHeight.py:341: DeprecationWarning: invalid escape sequence '\d'
  if re.match("T(\d*)", line):
2024-04-16 15:15:35,773 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: PauseAtHeight
2024-04-16 15:15:35,774 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: RetractContinue
2024-04-16 15:15:35,775 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: SearchAndReplace
2024-04-16 15:15:35,778 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: Stretch
2024-04-16 15:15:35,779 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: TimeLapse
2024-04-16 15:15:35,779 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin.loadScripts [217]: Begin loading of script: UsePreviousProbeMeasurements
2024-04-16 15:15:35,941 - DEBUG - [MainThread] PostProcessingPlugin.PostProcessingPlugin._createView [365]: Post processing view created.
2024-04-16 15:15:36,207 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/PrintSetupSelector/ProfileWarningReset.qml:77: TypeError: Cannot read property 'name' of null
2024-04-16 15:15:36,208 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/PrintSetupSelector/ProfileWarningReset.qml:77: TypeError: Cannot read property 'name' of null
2024-04-16 15:15:36,208 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingSlider.qml:36:9: Unable to assign [undefined] to QString
2024-04-16 15:15:36,208 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingComboBox.qml:70:9: Unable to assign [undefined] to QString
2024-04-16 15:15:36,208 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingTextField.qml:57:9: Unable to assign [undefined] to QString
2024-04-16 15:15:36,208 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingTextField.qml:57:9: Unable to assign [undefined] to QString
2024-04-16 15:15:36,209 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingComboBox.qml:70: TypeError: Cannot read property 'id' of null
2024-04-16 15:15:36,209 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/PrintSetupSelector/Recommended/RecommendedSupportSelector.qml:60:17: Unable to assign [undefined] to int
2024-04-16 15:15:36,209 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Widgets/SingleSettingComboBox.qml:70:9: Unable to assign [undefined] to QString
2024-04-16 15:15:36,209 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/PrintSetupSelector/Recommended/RecommendedSupportSelector.qml:95:17: Unable to assign [undefined] to int
2024-04-16 15:15:36,209 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: <Unknown File>:
2024-04-16 15:15:36,215 - DEBUG - [MainThread] UM.Controller.setActiveStage [180]: Setting active stage to PrepareStage
2024-04-16 15:15:36,366 - DEBUG - [MainThread] cura.Machines.Models.NozzleModel._update [28]: Updating NozzleModel.
2024-04-16 15:15:36,367 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Menus/ConfigurationMenu/ConfigurationMenu.qml:278: TypeError: Cannot read property 'hasRemoteConnection' of null
2024-04-16 15:15:36,384 - WARNING - [MainThread] UM.Qt.QtApplication.__onQmlWarning [448]: file:///opt/cura/share/cura/resources/qml/Menus/ConfigurationMenu/CustomConfiguration.qml:188: TypeError: Cannot read property 'isEnabled' of null
2024-04-16 15:15:36,459 - DEBUG - [MainThread] UM.Controller.setActiveView [108]: Setting active view to SolidView
2024-04-16 15:15:36,475 - DEBUG - [MainThread] SliceInfoPlugin.SliceInfo._createDialog [76]: Creating dialog [MoreInfoWindow.qml]
2024-04-16 15:15:36,513 - DEBUG - [MainThread] ModelChecker.ModelChecker._createView [118]: Creating model checker view.
2024-04-16 15:15:36,523 - DEBUG - [MainThread] ModelChecker.ModelChecker._createView [127]: Model checker view created.
2024-04-16 15:15:36,529 - DEBUG - [MainThread] cura.CuraApplication.run [939]: Booting Cura took 4.3468711376190186 seconds
2024-04-16 15:15:36,529 - DEBUG - [JobQueueWorker [0]] cura.Machines.ContainerTree.run [174]: Started background loading of MachineNodes
2024-04-16 15:15:36,529 - DEBUG - [JobQueueWorker [0]] cura.Machines.ContainerTree.run [185]: All MachineNode loading completed
2024-04-16 15:15:36,530 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [74]: Updating IntentSelectionModel.
2024-04-16 15:15:36,530 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [79]: No active GlobalStack, set quality profile model as empty.
2024-04-16 15:15:36,531 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [74]: Updating IntentSelectionModel.
2024-04-16 15:15:36,531 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [79]: No active GlobalStack, set quality profile model as empty.
2024-04-16 15:15:36,531 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [74]: Updating IntentSelectionModel.
2024-04-16 15:15:36,531 - DEBUG - [MainThread] cura.Machines.Models.IntentSelectionModel._update [79]: No active GlobalStack, set quality profile model as empty.
2024-04-16 15:15:36,635 - DEBUG - [MainThread] cura.Machines.Models.CustomQualityProfilesDropDownMenuModel._update [33]: Updating CustomQualityProfilesDropDownMenuModel.
2024-04-16 15:15:36,635 - DEBUG - [MainThread] cura.Machines.Models.CustomQualityProfilesDropDownMenuModel._update [38]: No active GlobalStack, set CustomQualityProfilesDropDownMenuModel as empty.
2024-04-16 15:15:36,667 - DEBUG - [MainThread] UM.View.GL.OpenGL.__init__ [121]: Initialized OpenGL subsystems.
2024-04-16 15:15:36,667 - DEBUG - [MainThread] UM.View.GL.OpenGL.__init__ [122]: OpenGL Version:  4.5 (Core Profile) Mesa 22.3.6
2024-04-16 15:15:36,667 - DEBUG - [MainThread] UM.View.GL.OpenGL.__init__ [123]: OpenGL Vendor:   Mesa/X.org
2024-04-16 15:15:36,668 - DEBUG - [MainThread] UM.View.GL.OpenGL.__init__ [124]: OpenGL Renderer: llvmpipe (LLVM 15.0.6, 256 bits)
2024-04-16 15:15:36,668 - DEBUG - [MainThread] UM.View.GL.OpenGL.__init__ [125]: GLSL Version:    4.50.0
2024-04-16 15:15:36,668 - DEBUG - [MainThread] UM.Qt.QtRenderer._initialize [211]: Support for Vertex Array Objects: True
2024-04-16 15:15:36,669 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/default.shader]...
2024-04-16 15:15:36,671 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/selection.shader]...
2024-04-16 15:15:36,672 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/select_face.shader]...
2024-04-16 15:15:36,674 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/default.shader]...
2024-04-16 15:15:36,676 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/composite.shader]...
2024-04-16 15:15:36,679 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/overhang.shader]...
2024-04-16 15:15:36,682 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/striped.shader]...
2024-04-16 15:15:36,685 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/transparent_object.shader]...
2024-04-16 15:15:36,687 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/striped.shader]...
2024-04-16 15:15:36,688 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/xray.shader]...
2024-04-16 15:15:36,689 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/xray_composite.shader]...
2024-04-16 15:15:36,693 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/uranium/resources/shaders/platform.shader]...
2024-04-16 15:15:36,702 - DEBUG - [MainThread] UM.View.GL.ShaderProgram.load [61]: Loading [/opt/cura/share/cura/resources/shaders/xray.shader]...
2024-04-16 15:15:46,630 - DEBUG - [MainThread] cura.AutoSave._onTimeout [64]: Autosaving preferences, instances and profiles took 0.07848620414733887 seconds
2024-04-16 15:15:59,448 - ERROR - [MainThread] CuraEngineBackend.CuraEngineBackend._onBackendQuit [1038]: Backend exited abnormally with return code -9!
root@6e9bb2311244:/# cat ~/.local/share/cura/stdout.log
root@6e9bb2311244:/#

@thelamer thelamer self-assigned this Apr 17, 2024
@thelamer
Copy link
Member

I'll see if I can replicate tomorrow

@thelamer
Copy link
Member

thelamer commented Apr 17, 2024

Cura on init balloons to about 4 gigs on my machine, but that is just stock Cura, then seems to run some garbage collection and drop down to a couple hundred megs. (it seems to be running a slice on init as it splashes a message that slicing failed)

When slicing though this thing is basically unusable I loaded up tiny gears and such and it shoots up to 8 gigs, is this software really only running on Windows? (I did a local install of the appimage to ensure it was not something Docker is doing)

This seems like it is upstream, there is something wrong here.

@thelamer thelamer added the work-in-progress Stale exempt label Apr 17, 2024
@thelamer
Copy link
Member

@roobre 100% upstream, try the tag linuxserver/cura:5.6.0-ls18 it is like a completely different piece of software.

@roobre
Copy link
Author

roobre commented Apr 18, 2024

I think it's likely the issue stems from upstream somehow, but I cannot reproduce it outside of LS image. If I run 5.7.0 on my arch workstation, it does not baloon, OOM, or consume a large amount of RAM:
image
image

Perhaps something environmental is triggering this?

@thelamer
Copy link
Member

Then it has to be Debian in this case, I did test the appimage on my machine and noticed the same behavior.
Do you run Wayland by chance? Wonder if it's X.

@roobre
Copy link
Author

roobre commented Apr 18, 2024

I run X, moving to wayland it's been on my todo list since forever but unfortunately for me hasn't happened yet 😓

@thelamer
Copy link
Member

Does this work for you:

lsiodev/cura:5.7.0-pkg-d1605b4a-dev-f9df3f6e37e8f390d645aec72bbe0697f77cc0aa

I can slice now, but I still get an initial balloon up, all I am doing here is dbus-launching the application.

@roobre
Copy link
Author

roobre commented Apr 18, 2024

No dice, unfortunately. I can see the thing trying to hog 11+GB

image

[942417.622845] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/system.slice/docker-03faf01a8d6e43642f52ca34080abdc310e70019bd3b84da0b50afdfae784288.scope,task=CuraEngine,pid=1761841,uid=911
[942417.622857] Out of memory: Killed process 1761841 (CuraEngine) total-vm:50362448kB, anon-rss:22096832kB, file-rss:384kB, shmem-rss:0kB, UID:911 pgtables:49840kB oom_score_adj:0

@thelamer
Copy link
Member

Try not mounting in config or removing /config/.config/openbox/autostart and restarting

@roobre
Copy link
Author

roobre commented Apr 18, 2024

The result I posted earlier was with a run without any prior data or anything mounted, just docker run --rm -i lsiodev/cura:5.7.0-pkg-d1605b4a-dev-f9df3f6e37e8f390d645aec72bbe0697f77cc0aa

If I mount an empty file in the path you suggested, something like docker run --rm -i -v /dev/null:/config/.config/openbox/autostart lsiodev/cura:5.7.0-pkg-d1605b4a-dev-f9df3f6e37e8f390d645aec72bbe0697f77cc0aa, then no cura process starts up (I guess that's expected).

@roobre
Copy link
Author

roobre commented Apr 23, 2024

@thelamer LMK if you need me to test anything else, more info on my environment or anything along those lines. And thanks for taking a look at this!

@roobre
Copy link
Author

roobre commented Jun 10, 2024

I've been able to reproduce this in most releases since 5.7.0, last one I've tested being ghcr.io/linuxserver/cura:5.7.2-RC2-ls30.

I tried to do some digging and see if I were able to figure out why this allocates memory nonstop in docker but not "bare metal", but I wasn't able to find anything. FWIW all different distro variants of the kasmvn image yielded the same behavior.

I'm still available and open to do more experiments on my end if anyone has promising leads.

@thelamer
Copy link
Member

Honestly I don't know what else to test or change from a Docker container perspective. It makes it much more difficult that I am unable to reproduce specifically what you are seeing on the latest version.
I saw some improvement with dbus-launch previously, but with 5.7.2 I am able to load and slice models.

@roobre
Copy link
Author

roobre commented Jun 10, 2024

@thelamer That is interesting, makes me wonder why it is behaving differently for the same container.
What docker version and OS are you using? Can you still slice if you run this image with --memory=4G?

I might try and set up a VM with the same OS you're using, to see if I it crashes for me in there 🤔

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
work-in-progress Stale exempt
Projects
Status: Issues
Development

No branches or pull requests

3 participants