Machines start up after being suspended

Hi. I have gotten most of mye power management the way i want it, i only have one problem.

After suspending a machine, it stays off for about 10-20 seconds before it starts up again, even if i have nothing queued up.

It would be nice if someone knows whats up :slight_smile:

Here is my log info.

2018-08-23 12:21:55:  BEGIN - RAYMONDPC\Raymond
2018-08-23 12:21:55:  Operating System: Windows 10 Pro
2018-08-23 12:21:55:  CPU Architecture: x64
2018-08-23 12:21:55:  CPUs: 24
2018-08-23 12:21:55:  Video Card: NVIDIA GeForce GTX 1080
2018-08-23 12:21:55:  Deadline Launcher 10.0 [v10.0.16.6 Release (c18da6bc1)]
2018-08-23 12:21:55:  Auto Configuration: Picking configuration based on: RAYMONDPC / 10.0.114.125
2018-08-23 12:21:55:  Auto Configuration: No auto configuration could be detected, using local configuration
2018-08-23 12:21:55:  Launcher Thread - Launcher thread initializing...
2018-08-23 12:21:55:  creating local listening socket on an available port...
2018-08-23 12:21:55:  updating local listening port in launcher file: 49723
2018-08-23 12:21:55:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 12:21:55:  Launching Slave: 
2018-08-23 12:21:59:  Launcher Thread - Remote Administration is now enabled
2018-08-23 12:21:59:  Launcher Thread - Automatic Updates is now disabled
2018-08-23 12:46:17:  ::ffff:10.0.114.142 has connected
2018-08-23 12:46:17:  Launcher Thread - Received command: SuspendMachine
2018-08-23 12:46:17:  Sending command to slave: StopSlave 
2018-08-23 12:46:17:  Got reply: RAYMONDPC: Sent "StopSlave" command. Result: "Connection Accepted.
2018-08-23 12:46:17:  "
2018-08-23 12:46:20:  No Monitor to shutdown
2018-08-23 12:46:20:  No Pulse to shutdown
2018-08-23 12:46:20:  Launcher Thread - Responded with: Success
2018-08-23 12:46:25:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 12:46:25:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 12:46:25:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 12:48:18:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 12:48:18:  Launcher Thread - Launcher thread initializing...
2018-08-23 12:48:18:  creating local listening socket on an available port...
2018-08-23 12:48:18:  updating local listening port in launcher file: 52029
2018-08-23 12:48:18:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 12:48:18:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 12:48:28:  Launching Slave: 
2018-08-23 13:51:23:  ::ffff:10.0.114.130 has connected
2018-08-23 13:51:23:  Launcher Thread - Received command: OnLastTaskComplete SuspendMachineIdle :  : ~
2018-08-23 13:51:23:  Sending command to slave: OnLastTaskComplete 
2018-08-23 13:51:23:  Got reply: RAYMONDPC: Sent "OnLastTaskComplete SuspendMachineIdle  :   " command. Result: "Connection Accepted.
2018-08-23 13:51:23:  "
2018-08-23 13:51:23:  Launcher Thread - OnLastTaskComplete: Set to SuspendMachineIdle
2018-08-23 13:51:23:  Launcher Thread - Responded with: Success
2018-08-23 13:51:27:  Launcher Thread - OnLastTaskComplete: Checking if all slaves have shutdown
2018-08-23 13:51:27:  Launcher Thread - OnLastTaskComplete: Suspending machine because all slaves have shutdown
2018-08-23 13:51:32:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 13:51:32:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 13:51:32:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 13:53:55:  Error occurred while updating network settings: An error occurred while trying to connect to the Database (10.0.114.130:27100). It is possible that the Mongo Database server is incorrectly configured, currently offline, blocked by a firewall, or experiencing network issues.
2018-08-23 13:53:55:  Full error: An exception occurred while opening a connection to the server. (FranticX.Database.DatabaseConnectionException)
2018-08-23 13:53:55:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(String connectionString, Exception ex)
2018-08-23 13:53:55:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(MongoServer server, Exception ex)
2018-08-23 13:53:55:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(DeadlineMongoDB database, Exception ex)
2018-08-23 13:53:55:     at Deadline.StorageDB.MongoDB.MongoSettingsStorage.GetNetworkSettings(Boolean invalidateCache)
2018-08-23 13:53:55:     at Deadline.StorageDB.SettingsStorage.GetRecentNetworkSettings()
2018-08-23 13:53:55:  ---------- Inner Stack Trace (System.Net.Sockets.SocketException) ----------
2018-08-23 13:53:55:     at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
2018-08-23 13:53:55:     at System.Net.Sockets.Socket.Connect(EndPoint remoteEP)
2018-08-23 13:53:55:     at MongoDB.Driver.Core.Connections.TcpStreamFactory.Connect(Socket socket, EndPoint endPoint, CancellationToken cancellationToken)
2018-08-23 13:53:55:     at MongoDB.Driver.Core.Connections.TcpStreamFactory.CreateStream(EndPoint endPoint, CancellationToken cancellationToken)
2018-08-23 13:53:55:     at MongoDB.Driver.Core.Connections.SslStreamFactory.CreateStream(EndPoint endPoint, CancellationToken cancellationToken)
2018-08-23 13:53:55:     at MongoDB.Driver.Core.Connections.BinaryConnection.OpenHelper(CancellationToken cancellationToken)
2018-08-23 13:53:56:  Launcher Scheduling - Error occurred while checking Slave Scheduling options: An error occurred while trying to connect to the Database (10.0.114.130:27100). It is possible that the Mongo Database server is incorrectly configured, currently offline, blocked by a firewall, or experiencing network issues.
2018-08-23 13:53:56:  Full error: An exception occurred while opening a connection to the server. (FranticX.Database.DatabaseConnectionException)
2018-08-23 13:53:56:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 13:53:56:  Launcher Thread - Launcher thread initializing...
2018-08-23 13:53:56:  creating local listening socket on an available port...
2018-08-23 13:53:56:  updating local listening port in launcher file: 57901
2018-08-23 13:53:56:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 13:53:56:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 13:54:06:  Launching Slave: 
2018-08-23 14:57:10:  ::ffff:10.0.114.130 has connected
2018-08-23 14:57:10:  Launcher Thread - Received command: OnLastTaskComplete SuspendMachineIdle :  : ~
2018-08-23 14:57:10:  Sending command to slave: OnLastTaskComplete 
2018-08-23 14:57:11:  Got reply: RAYMONDPC: Sent "OnLastTaskComplete SuspendMachineIdle  :   " command. Result: "Connection Accepted.
2018-08-23 14:57:11:  "
2018-08-23 14:57:11:  Launcher Thread - OnLastTaskComplete: Set to SuspendMachineIdle
2018-08-23 14:57:11:  Launcher Thread - Responded with: Success
2018-08-23 14:57:15:  Launcher Thread - OnLastTaskComplete: Checking if all slaves have shutdown
2018-08-23 14:57:15:  Launcher Thread - OnLastTaskComplete: Suspending machine because all slaves have shutdown
2018-08-23 14:57:20:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 14:57:20:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 14:57:20:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 14:57:42:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 14:57:42:  Launcher Thread - Launcher thread initializing...
2018-08-23 14:57:42:  creating local listening socket on an available port...
2018-08-23 14:57:42:  updating local listening port in launcher file: 63857
2018-08-23 14:57:42:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 14:57:42:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 14:57:52:  Launching Slave: 
2018-08-23 14:58:16:  Launcher Scheduling - Stopping slave raymondpc because this machine is no longer idle (scheduling group "AllSlaves")
2018-08-23 14:58:16:  Sending command to slave: StopSlave 
2018-08-23 14:58:16:  Got reply: RAYMONDPC: Sent "StopSlave" command. Result: "Connection Accepted.
2018-08-23 14:58:16:  "
2018-08-23 15:24:18:  The network path was not found.
2018-08-23 15:24:22:  Error occurred while updating network settings: A connection attempt timed out when trying to reach the Database (10.0.114.130:27100). Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2018-08-23 15:24:22:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(String connectionString, Exception ex)
2018-08-23 15:24:22:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(MongoServer server, Exception ex)
2018-08-23 15:24:22:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(DeadlineMongoDB database, Exception ex)
2018-08-23 15:24:22:     at Deadline.StorageDB.MongoDB.MongoSettingsStorage.GetNetworkSettings(Boolean invalidateCache)
2018-08-23 15:24:22:     at Deadline.StorageDB.SettingsStorage.GetRecentNetworkSettings()
2018-08-23 15:24:22:  ---------- Inner Stack Trace (System.TimeoutException) ----------
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Clusters.Cluster.ThrowTimeoutException(IServerSelector selector, ClusterDescription description)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Clusters.Cluster.WaitForDescriptionChangedHelper.HandleCompletedTask(Task completedTask)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Clusters.Cluster.WaitForDescriptionChanged(IServerSelector selector, ClusterDescription description, Task descriptionChangedTask, TimeSpan timeout, CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Clusters.Cluster.SelectServer(IServerSelector selector, CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Bindings.WritableServerBinding.GetReadChannelSource(CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Bindings.ReadWriteBindingHandle.GetReadChannelSource(CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.Core.Operations.FindOperation`1.Execute(IReadBinding binding, CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.DefaultLegacyOperationExecutor.ExecuteReadOperation[TResult](IReadBinding binding, IReadOperation`1 operation, CancellationToken cancellationToken)
2018-08-23 15:24:22:     at MongoDB.Driver.MongoCollection.ExecuteReadOperation[TResult](IClientSessionHandle session, IReadOperation`1 operation, ReadPreference readPreference)
2018-08-23 15:24:22:     at MongoDB.Driver.MongoCollection.FindOneAs[TDocument](IClientSessionHandle session, FindOneArgs args)
2018-08-23 15:24:22:     at MongoDB.Driver.MongoCollection.UsingImplicitSession[TResult](Func`2 func)
2018-08-23 15:24:22:     at Deadline.StorageDB.MongoDB.MongoSettingsStorage.GetNetworkSettings(Boolean invalidateCache)
2018-08-23 15:24:32:  Launcher Scheduling - Error occurred while checking Slave Scheduling options: A connection attempt timed out when trying to reach the Database (10.0.114.130:27100). Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2018-08-23 15:27:52:  Launcher Scheduling - Error occurred while checking Slave Scheduling options: An error occurred while trying to connect to the Database (10.0.114.130:27100). It is possible that the Mongo Database server is incorrectly configured, currently offline, blocked by a firewall, or experiencing network issues.
2018-08-23 15:27:52:  Full error: An exception occurred while receiving a message from the server. (FranticX.Database.DatabaseConnectionException)
2018-08-23 16:14:59:  Launcher Scheduling - Launching slave raymondpc because this machine has been idle longer than 40 minutes (scheduling group "AllSlaves")
2018-08-23 16:14:59:  Launching Slave: raymondpc
2018-08-23 16:46:14:  Launcher Scheduling - Error occurred while checking Slave Scheduling options: A connection attempt timed out when trying to reach the Database (10.0.114.130:27100). Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2018-08-23 18:04:36:  Launcher Scheduling - Error occurred while checking Slave Scheduling options: A connection attempt timed out when trying to reach the Database (10.0.114.130:27100). Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2018-08-23 18:05:06:  Error occurred while updating network settings: A connection attempt timed out when trying to reach the Database (10.0.114.130:27100). Please ensure that the specified address is correct and reachable. (FranticX.Database.DatabaseConnectionException)
2018-08-23 18:05:06:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(String connectionString, Exception ex)
2018-08-23 18:05:06:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(MongoServer server, Exception ex)
2018-08-23 18:05:06:     at Deadline.StorageDB.MongoDB.MongoDBUtils.HandleException(DeadlineMongoDB database, Exception ex)
2018-08-23 18:05:06:     at Deadline.StorageDB.MongoDB.MongoSettingsStorage.GetNetworkSettings(Boolean invalidateCache)
2018-08-23 18:05:06:     at Deadline.StorageDB.SettingsStorage.GetRecentNetworkSettings()
2018-08-23 18:05:06:  ---------- Inner Stack Trace (System.TimeoutException) ----------
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Clusters.Cluster.ThrowTimeoutException(IServerSelector selector, ClusterDescription description)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Clusters.Cluster.WaitForDescriptionChangedHelper.HandleCompletedTask(Task completedTask)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Clusters.Cluster.WaitForDescriptionChanged(IServerSelector selector, ClusterDescription description, Task descriptionChangedTask, TimeSpan timeout, CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Clusters.Cluster.SelectServer(IServerSelector selector, CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Bindings.WritableServerBinding.GetReadChannelSource(CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Bindings.ReadWriteBindingHandle.GetReadChannelSource(CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.Core.Operations.FindOperation`1.Execute(IReadBinding binding, CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.DefaultLegacyOperationExecutor.ExecuteReadOperation[TResult](IReadBinding binding, IReadOperation`1 operation, CancellationToken cancellationToken)
2018-08-23 18:05:06:     at MongoDB.Driver.MongoCollection.ExecuteReadOperation[TResult](IClientSessionHandle session, IReadOperation`1 operation, ReadPreference readPreference)
2018-08-23 18:05:06:     at MongoDB.Driver.MongoCollection.FindOneAs[TDocument](IClientSessionHandle session, FindOneArgs args)
2018-08-23 18:05:06:     at MongoDB.Driver.MongoCollection.UsingImplicitSession[TResult](Func`2 func)
2018-08-23 18:05:06:     at Deadline.StorageDB.MongoDB.MongoSettingsStorage.GetNetworkSettings(Boolean invalidateCache)
2018-08-23 18:14:04:  ::ffff:10.0.114.142 has connected
2018-08-23 18:14:04:  Launcher Thread - Received command: SuspendMachine
2018-08-23 18:14:04:  Sending command to slave: StopSlave 
2018-08-23 18:14:04:  Got reply: RAYMONDPC: Sent "StopSlave" command. Result: "Connection Accepted.
2018-08-23 18:14:04:  "
2018-08-23 18:14:06:  No Monitor to shutdown
2018-08-23 18:14:06:  No Pulse to shutdown
2018-08-23 18:14:06:  Launcher Thread - Responded with: Success
2018-08-23 18:14:11:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 18:14:11:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:14:11:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:14:49:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 18:14:49:  Launcher Thread - Launcher thread initializing...
2018-08-23 18:14:49:  creating local listening socket on an available port...
2018-08-23 18:14:49:  updating local listening port in launcher file: 65090
2018-08-23 18:14:49:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 18:14:49:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 18:14:55:  Launcher Scheduling - Launching slave raymondpc because this machine has been idle longer than 40 minutes (scheduling group "AllSlaves")
2018-08-23 18:14:55:  Launching Slave: raymondpc
2018-08-23 18:14:55:  Launcher Scheduling - Launching slave raymondpc because this machine has been idle longer than 40 minutes (scheduling group "AllSlaves")
2018-08-23 18:14:55:  Launching Slave: raymondpc
2018-08-23 18:15:54:  ::ffff:10.0.114.142 has connected
2018-08-23 18:15:54:  Launcher Thread - Received command: SuspendMachine
2018-08-23 18:15:54:  Sending command to slave: StopSlave 
2018-08-23 18:15:54:  Got reply: RAYMONDPC: Sent "StopSlave" command. Result: "Connection Accepted.
2018-08-23 18:15:54:  "
2018-08-23 18:15:56:  No Monitor to shutdown
2018-08-23 18:15:56:  No Pulse to shutdown
2018-08-23 18:15:56:  Launcher Thread - Responded with: Success
2018-08-23 18:16:01:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 18:16:01:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:16:01:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:16:50:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 18:16:50:  Launcher Thread - Launcher thread initializing...
2018-08-23 18:16:50:  creating local listening socket on an available port...
2018-08-23 18:16:50:  updating local listening port in launcher file: 65238
2018-08-23 18:16:50:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 18:16:50:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 18:17:00:  Launching Slave: 
2018-08-23 18:21:02:  Launcher Scheduling - Stopping slave raymondpc because this machine is no longer idle (scheduling group "AllSlaves")
2018-08-23 18:21:02:  Sending command to slave: StopSlave 
2018-08-23 18:21:02:  Got reply: RAYMONDPC: Sent "StopSlave" command. Result: "Connection Accepted.
2018-08-23 18:21:02:  "
2018-08-23 18:23:22:  ::ffff:10.0.114.142 has connected
2018-08-23 18:23:22:  Launcher Thread - Received command: SuspendMachine
2018-08-23 18:23:22:  No Slave to shutdown
2018-08-23 18:23:22:  No Monitor to shutdown
2018-08-23 18:23:22:  No Pulse to shutdown
2018-08-23 18:23:22:  Launcher Thread - Responded with: Success
2018-08-23 18:23:27:  Launcher Thread - Machine is about to be suspended, shutting down launcher thread...
2018-08-23 18:23:27:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:23:27:  Launcher Thread - OnConnect: Listener Socket has been closed.
2018-08-23 18:24:12:  Launcher Thread - Machine is about to be resumed, initializing launcher thread...
2018-08-23 18:24:12:  Launcher Thread - Launcher thread initializing...
2018-08-23 18:24:12:  creating local listening socket on an available port...
2018-08-23 18:24:12:  updating local listening port in launcher file: 49273
2018-08-23 18:24:12:  Launcher Thread - Launcher thread listening on port 17000
2018-08-23 18:24:12:  Launcher Thread - Client is configured to launch Slave at startup, waiting 10 seconds to allow system to resume before launching Slave...
2018-08-23 18:24:22:  Launching Slave:

Is there a keyboard/mouse attached to the machine? I’m wondering if there’s something in the BIOS that says “wake on human interface device”. I’d do the usual Googling to see what else might be waking it up. Windows shouldn’t know why because it’s obviously not running yet. :slight_smile:

There are only two things inside Deadline that would be waking machines. One is manual, which is the Monitor’s “Start Machine” command and the other is automatic through Pulse. If Pulse is to blame here, you’ll see it in its log. Docs for that are here:
https://docs.thinkboxsoftware.com/products/deadline/10.0/1_User%20Manual/manual/logs.html#pulse