Houdini - Error / Please Help

Alulla_Alemayehu · February 7, 2023, 7:23pm

Hi there,

I am a new User to Deadline. Unfortunately, I am facing from the beginning onwards multiple issues. I been able to submit my work, but either get rendered with multiple assets missing in the scene or does even bother at all to render. I have looked into the log file but most of it I do not understand.

I appreciate any help.

2023-02-07 19:43:34: BEGIN - MININT-N80POJ1\AlullaAlemayehu
2023-02-07 19:43:34: Operating System: Windows 10 Pro
2023-02-07 19:43:34: CPU Architecture: x64
2023-02-07 19:43:34: CPUs: 64
2023-02-07 19:43:34: Video Card: NVIDIA GeForce RTX 3090
2023-02-07 19:43:34: Deadline Launcher 10.2 [v10.2.0.10 Release (3b87216c7)]
2023-02-07 19:43:34: Auto Configuration: Picking configuration based on: MININT-N80POJ1 / 2001:9e8:967:6900:85b6:e362:44b3:1566
2023-02-07 19:43:34: Auto Configuration: No auto configuration could be detected, using local configuration
2023-02-07 19:43:34: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 19:43:34: Local python API (Python 3): Updating
2023-02-07 19:43:34: ‘C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\pythonAPIs\LzpSIezHU5KgVLKWHPqCQg==’ already exists. Skipping extraction of PythonSync.
2023-02-07 19:43:34: Local python API (Python 3): Update complete
2023-02-07 19:43:34: PythonSync Fallback (Python 3): Attempting to decompress pythonsync3 from C:\DeadlineRepository10\pythonsync3\pythonsync3.zip to C:\Program Files\Thinkbox\Deadline10\bin\pythonsync3
2023-02-07 19:43:34: WARNING: PythonSync (Python 3) upgrade failed with the following error:
2023-02-07 19:43:34: Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\pythonsync3\pyparsing.py’ is denied.
2023-02-07 19:43:34: Local python API (Python 2): Updating
2023-02-07 19:43:34: ‘C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\pythonAPIs\e+XP82G2CFZg5W58vsFhg==’ already exists. Skipping extraction of PythonSync.
2023-02-07 19:43:34: Local python API (Python 2): Update complete
2023-02-07 19:43:34: PythonSync Fallback (Python 2): Attempting to decompress pythonsync from C:\DeadlineRepository10\pythonsync\pythonsync.zip to C:\Program Files\Thinkbox\Deadline10\bin\pythonsync
2023-02-07 19:43:34: WARNING: PythonSync (Python 2) upgrade failed with the following error:
2023-02-07 19:43:34: Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\pythonsync\configparser.py’ is denied.
2023-02-07 19:43:34: Launcher Thread - Launcher thread initializing…
2023-02-07 19:43:34: Launcher Thread - opening remote TCP listening port 17000
2023-02-07 19:43:34: Launcher Thread - creating local listening TCP socket on an available port…
2023-02-07 19:43:34: Launcher Thread - local TCP port bound to: [::1]:53982
2023-02-07 19:43:34: Launcher Thread - updating local listening port in launcher file: 53982
2023-02-07 19:43:34: Launcher Thread - Launcher thread listening on port 17000
2023-02-07 19:43:34: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 19:43:35: Launcher Thread - Remote Administration is now disabled
2023-02-07 19:43:35: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 19:43:35: Launcher Thread - Automatic Updates is now disabled
2023-02-07 19:44:00: Comparing Deployment Versions of Repository and Client to validate if auto upgrade is supported.
2023-02-07 19:44:00: Repository and Client Deployment Versions are supported for auto upgrade.
2023-02-07 19:44:00: IsLocalBinFolderWritable: Cannot touch file C:\Program Files\Thinkbox\Deadline10\bin\TestFile_85e84e22-d6ed-4ea7-a390-12ad8d1662b9. Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\TestFile_85e84e22-d6ed-4ea7-a390-12ad8d1662b9’ is denied.
2023-02-07 19:44:00: Auto-upgrade aborted. Current running user does not have the write permission to the local bin folder.
2023-02-07 19:44:00: Launching Monitor
2023-02-07 19:48:36: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 19:53:37: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 19:58:38: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 20:03:39: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 20:08:40: [Info] Auto-upgrade is blocked by root/administrator via C:\Users\AlullaAlemayehu\AppData\Local\Thinkbox\Deadline10\secure.ini.
2023-02-07 20:10:51: ERROR: GetRepositoryDateTime: A timeout occurred after 10000ms selecting a server using CompositeServerSelector{ Selectors = MongoDB.Driver.MongoClient+AreSessionsSupportedServerSelector, LatencyLimitingServerSelector{ AllowedLatencyRange = 00:00:00.0150000 }, OperationsCountServerSelector }. Client view of cluster state is { ClusterId : “1”, ConnectionMode : “Direct”, Type : “Standalone”, State : “Disconnected”, Servers : [{ ServerId: “{ ClusterId : 1, EndPoint : “10.5.0.2:27100” }”, EndPoint: “10.5.0.2:27100”, ReasonChanged: “Heartbeat”, State: “Disconnected”, ServerVersion: , TopologyVersion: , Type: “Unknown”, HeartbeatException: “MongoDB.Driver.MongoConnectionException: An exception occurred while opening a connection to the server.
2023-02-07 20:10:51: —> System.TimeoutException: Timed out connecting to 10.5.0.2:27100. Timeout was 00:00:10.
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.ConnectAsync(Socket socket, EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.CreateStreamAsync(EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: — End of inner exception stack trace —
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.InitializeConnectionAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.HeartbeatAsync(CancellationToken cancellationToken)”, LastHeartbeatTimestamp: “2023-02-07T19:10:47.4421942Z”, LastUpdateTimestamp: “2023-02-07T19:10:47.4421943Z” }] }. (System.TimeoutException)
2023-02-07 20:10:51: ERROR: GetRepositoryDateTime: A timeout occurred after 10000ms selecting a server using CompositeServerSelector{ Selectors = MongoDB.Driver.MongoClient+AreSessionsSupportedServerSelector, LatencyLimitingServerSelector{ AllowedLatencyRange = 00:00:00.0150000 }, OperationsCountServerSelector }. Client view of cluster state is { ClusterId : “1”, ConnectionMode : “Direct”, Type : “Standalone”, State : “Disconnected”, Servers : [{ ServerId: “{ ClusterId : 1, EndPoint : “10.5.0.2:27100” }”, EndPoint: “10.5.0.2:27100”, ReasonChanged: “Heartbeat”, State: “Disconnected”, ServerVersion: , TopologyVersion: , Type: “Unknown”, HeartbeatException: “MongoDB.Driver.MongoConnectionException: An exception occurred while opening a connection to the server.
2023-02-07 20:10:51: —> System.TimeoutException: Timed out connecting to 10.5.0.2:27100. Timeout was 00:00:10.
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.ConnectAsync(Socket socket, EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.CreateStreamAsync(EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: — End of inner exception stack trace —
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.InitializeConnectionAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.HeartbeatAsync(CancellationToken cancellationToken)”, LastHeartbeatTimestamp: “2023-02-07T19:10:47.4421942Z”, LastUpdateTimestamp: “2023-02-07T19:10:47.4421943Z” }] }. (System.TimeoutException)
2023-02-07 20:10:51: ERROR: GetRepositoryDateTime: A timeout occurred after 10000ms selecting a server using CompositeServerSelector{ Selectors = MongoDB.Driver.MongoClient+AreSessionsSupportedServerSelector, LatencyLimitingServerSelector{ AllowedLatencyRange = 00:00:00.0150000 }, OperationsCountServerSelector }. Client view of cluster state is { ClusterId : “1”, ConnectionMode : “Direct”, Type : “Standalone”, State : “Disconnected”, Servers : [{ ServerId: “{ ClusterId : 1, EndPoint : “10.5.0.2:27100” }”, EndPoint: “10.5.0.2:27100”, ReasonChanged: “Heartbeat”, State: “Disconnected”, ServerVersion: , TopologyVersion: , Type: “Unknown”, HeartbeatException: “MongoDB.Driver.MongoConnectionException: An exception occurred while opening a connection to the server.
2023-02-07 20:10:51: —> System.TimeoutException: Timed out connecting to 10.5.0.2:27100. Timeout was 00:00:10.
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.ConnectAsync(Socket socket, EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.TcpStreamFactory.CreateStreamAsync(EndPoint endPoint, CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: — End of inner exception stack trace —
2023-02-07 20:10:51: at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelperAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.InitializeConnectionAsync(CancellationToken cancellationToken)
2023-02-07 20:10:51: at MongoDB.Driver.Core.Servers.ServerMonitor.HeartbeatAsync(CancellationToken cancellationToken)”, LastHeartbeatTimestamp: “2023-02-07T19:10:33.3322313Z”, LastUpdateTimestamp: “2023-02-07T19:10:33.3322315Z” }] }. (System.TimeoutException)
2023-02-07 20:10:51: ERROR: Launcher Scheduling - Error occurred while checking Worker Scheduling options: A connection attempt timed out when trying to reach the Database 10.5.0.2:27100. Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2023-02-07 20:10:52: Comparing Deployment Versions of Repository and Client to validate if auto upgrade is supported.
2023-02-07 20:10:52: Repository and Client Deployment Versions are supported for auto upgrade.
2023-02-07 20:10:52: IsLocalBinFolderWritable: Cannot touch file C:\Program Files\Thinkbox\Deadline10\bin\TestFile_6b95e525-863b-49c3-824a-635adc9a346c. Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\TestFile_6b95e525-863b-49c3-824a-635adc9a346c’ is denied.
2023-02-07 20:10:52: Auto-upgrade aborted. Current running user does not have the write permission to the local bin folder.
2023-02-07 20:11:20: Comparing Deployment Versions of Repository and Client to validate if auto upgrade is supported.
2023-02-07 20:11:20: Repository and Client Deployment Versions are supported for auto upgrade.
2023-02-07 20:11:20: IsLocalBinFolderWritable: Cannot touch file C:\Program Files\Thinkbox\Deadline10\bin\TestFile_3d8c1775-0b7c-4aea-b9b8-6cd8e0df954a. Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\TestFile_3d8c1775-0b7c-4aea-b9b8-6cd8e0df954a’ is denied.
2023-02-07 20:11:20: Auto-upgrade aborted. Current running user does not have the write permission to the local bin folder.
2023-02-07 20:11:20: Launching Worker: MININT-N80POJ1
2023-02-07 20:12:01: ERROR: Launcher Scheduling - Error occurred while checking Worker Scheduling options: A connection attempt timed out when trying to reach the Database 10.5.0.2:27100. Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)

mois.moshev · February 9, 2023, 7:06am

Your mongodb server could not be reached. You might need to reconfigure if something changed. I think it is in <repo>/settings/connection.ini.

Also, your user does not have permissions, for some reason it is trying to write in Program Files

2023-02-07 20:11:20: IsLocalBinFolderWritable: Cannot touch file C:\Program Files\Thinkbox\Deadline10\bin\TestFile_3d8c1775-0b7c-4aea-b9b8-6cd8e0df954a. Access to the path ‘C:\Program Files\Thinkbox\Deadline10\bin\TestFile_3d8c1775-0b7c-4aea-b9b8-6cd8e0df954a’ is denied.
2023-02-07 20:11:20: Auto-upgrade aborted. Current running user does not have the write permission to the local bin folder.

I don’t know if this is fatal.

karpreet · February 9, 2023, 8:24pm

+1 on @mois.moshev findings, your Database is either rejecting connections over network or the is running into issues. I would also check if this is an intermittent issue with DB connection, I would check the network.
You can check if something is wrong with DB, find the logs under DB install directory and see if it is reporting any issues.

The user permission error seems to be triggered when Deadline is trying to Auto-Upgrade and failing to write the test file as a part of permission checks. I would give out the permission to the Launcher user for performing the auto-upgrade successfully.

Alulla_Alemayehu · February 10, 2023, 9:34am

Hi Karpreet,

thank you for your help. I am running deadline only on one machine. Further I would like to highlight that I am a artist would like to use deadline for my render purposes. Looking into the log file, does not tell me anything, due lack of knowledge in this field.
log.zip (22.6 KB)

Thank you,