No feedback for Fusion jobs

eamsler · October 10, 2016, 3:37pm

The exit code hasn’t really gone wrong before this. I can only see possible problems if you have ‘render as user’ enabled in the user security settings.

I think we’ll need to include an option to ignore that exit code… Any issues with that idea from anyone?

NMTrix · October 11, 2016, 11:55am

Thanks for the reply.

I would have no problem with ignoring that exit code. But I would like to see the exitcode that fusion returned. There are already some exitcodes within the scripts that seems to be right (errorcode 10 for instance)

It would be helpfull if the ‘right’ exitcode will be used so we can see if a comp goes wrong.

Best,

Patrick

eamsler · October 11, 2016, 2:26pm

Deadline has never used the wrong exit code before. At least not that I can remember. It’s built into the operating system, so not much can go wrong without ‘run as user’.

Can you try running it from the command line and then running “echo %ERRORLEVEL%” immediately after? That should show whatever the true exit code was from the application. The error code from the program output though might be entirely different. We could just look for the text “Auto-exiting with errcode (\d+)” and that would return the number from that line.

Info on running from the command line is here:
docs.thinkboxsoftware.com/produc … oting.html

Maybe we can check for both? What errcodes should we check for?

NMTrix · December 8, 2016, 3:30pm

Hi Edwin,

Sorry for the silence. The %errorlevel% is -1073741819. Always. I checked with a batch job. So the command line version of fusion probably only returns that code. Which is a shame. I have attached a somewhat edited output.txt of the command line output.

As you can see Fusion reports an exiting with code 0 but somehow it doesn’t. I also read that the errorcode -1073741819 might have something to do with the Open CL drivers but I installed the latest version of them but it made no difference.

I am not sure if you can get the verbose output and check for this part.

Render completed successfully at Thu 4:16PM - Total Time: 0h 0m 5.63s, Average: 1.41 seconds/frame
Auto-exiting with errcode 0
Cleanup licenses

I hope this can be solved because if something goes wrong now the tasks will still be flagged as complete.

Best regards and thanks for your help!

Patrick
Output.txt (3.01 KB)

eamsler · December 12, 2016, 4:27pm

Okay! I’ll make a Git issue for that and we’ll hopefully be able to get something out for you either in the 8.0 service pack or 8.1 (not sure which will be out next). As usual, I’ll try to report back once it’s been implemented.

NMTrix · December 12, 2016, 4:32pm

Hi Edwin,

Thanks! Looking forward to the update.

It would be great if the team can also fix the normal (non commandline) plugin of Fusion. This has been mentioned before. The process is unable to kill the fuscript.exe becasue of a rights issue.

Best regards,

Patrick

eamsler · December 12, 2016, 4:51pm

Well, I just got feedback that the exit code problem is solved in SP12. Not sure on the standard Fusion plugin. Can you upgrade and try both again?

Alcium · February 2, 2017, 12:52pm

Digging back an old thread, I’ve been busy around and did not have time to provide further information

It seems both Fusion and FusionCmd plugins are inverting Stderr and Stdout

Provided are the logs and command used to obtain them

I confirm the bug is still present in 8.0.13
STDERR.txt (249 Bytes)
STDOUT.TXT (3.54 KB)
COMMAND.txt (300 Bytes)

eamsler · February 6, 2017, 4:09pm

The output seems alright. I’d expect non-normal operations like warnings and errors to come through STDERR and the rest to go to STDOUT. The good news is Deadline mushes them both into the same stream handling functions, so for us it doesn’t matter much. The good news is that SP12 added the “Exit code -1073741819 is no longer treated as an error during rendering.”, so you should be in good shape with Fusion now in SP13. Are you getting new errors?

Alcium · February 6, 2017, 5:03pm

Actually yes

To be clear I only see the STDERR catched by deadline

The STDOUT is just nowhere to be found
this poses problem as there is no progress report, but the most problematic is that it’s not catching on errors because of that

eamsler · February 7, 2017, 4:56pm

So, after digging into our issue tracker this may have been caused by a bad auto-upgrade. Would you be able to uninstall and re-install Deadline? We’d want to clear out the ‘bin’ directory before re-installing. The guess is that there may be a bad DLL.

If that makes no difference, it might be related to a different problem that we’re working on this week.

Alcium · February 7, 2017, 5:04pm

1649 jobs queued, I guess the timing’s not right

Alcium · February 7, 2017, 5:06pm

and if we’re talking about reinstalling the client, I actually do not go for autoupgrade

I got a script manually installing deadline each time a new release is pushed

Also the problem is present on all 330 nodes …

eamsler · February 7, 2017, 9:30pm

Oof. Well, we would only need to test on a single machine assuming the error is easily reproducible.

Alcium · March 1, 2017, 10:09am

Problem is the same with fresh installs …

We are still running SP15 now I guess it is not fixed yet in SP16
Any clue on your side ?

eamsler · March 1, 2017, 3:12pm

Actually, one of our dev team members fixed this about a week ago and it has been rolled into SP17 that was released yesterday. I was a bit off before, and if I remember the fix correctly, there was a mistake in how we named the cached logs, so they didn’t copy over correctly while reading them.

If it’s not fixed in SP17, we may have missed something and should set up a call.

Alcium · March 1, 2017, 4:46pm

Sadly seems to keep ongoing

Note that I have feedback on Fusion create quicktime jobs

Seems mainly the plugin ‘FusionCmd’ seems affected now

eamsler · March 2, 2017, 5:00pm

I hit this myself too. For me, it seemed that when running concurrent tasks the logs often get pushed into those for Render Thread 0 on the Slave. I’ve shown the dev team the ways to reproduce, but over here it seems intermittent. I’ll usually get logs that look like this:

Render thread 0:

=======================================================
Log
=======================================================
2017-03-01 16:27:55:  BEGIN - Mobile-029.local\edwin.amsler
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: Executing plugin script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/CommandLine.py'
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: About: Command Line support for Deadline
2017-03-01 16:27:55:  0: INFO: The job's environment will be merged with the current environment before rendering
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: Start Job timeout is disabled.
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: Task timeout is disabled.
2017-03-01 16:27:55:  0: Loaded job: Batch Bug Test (589cb2a0e9faf16a26bbcc35)
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: Plugin rendering frame(s): 1
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.056 ms
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Executing job preload script '/Users/edwin.amsler/Library/Application Support/Thinkbox/Deadline8/slave/mobile-029/plugins/589cb2a0e9faf16a26bbcc35/JobPreLoad.py'
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_LINUX to 
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Setting DEADLINE_CPUS_WINDOWS to 0
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.047 ms
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.041 ms
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.038 ms
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Stdout Redirection Enabled: True
2017-03-01 16:27:55:  0: INFO: Asynchronous Stdout Enabled: False
2017-03-01 16:27:55:  0: INFO: Stdout Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: Popup Handling Enabled: True
2017-03-01 16:27:55:  0: INFO: QT Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2017-03-01 16:27:55:  0: INFO: Using Process Tree: True
2017-03-01 16:27:55:  0: INFO: Hiding DOS Window: True
2017-03-01 16:27:55:  0: INFO: Creating New Console: False
2017-03-01 16:27:55:  0: INFO: Running as user: edwin.amsler
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.055 ms
2017-03-01 16:27:55:  0: INFO: Executable: "/usr/bin/env"
2017-03-01 16:27:55:  0: INFO: Argument: ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Full Command: "/usr/bin/env" ping -c 10 localhost
2017-03-01 16:27:55:  0: INFO: Startup Directory: "/usr/bin"
2017-03-01 16:27:55:  0: INFO: Process Priority: BelowNormal
2017-03-01 16:27:55:  0: INFO: Process Affinity: default
2017-03-01 16:27:55:  0: INFO: Process is now running
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.052 ms
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.064 ms
2017-03-01 16:27:55:  0: STDOUT: PING localhost (127.0.0.1): 56 data bytes
2017-03-01 16:27:55:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=0 ttl=64 time=0.036 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.097 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.103 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.079 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.071 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.051 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.088 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.068 ms
2017-03-01 16:27:56:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.077 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.071 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.108 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.061 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.093 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.084 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.067 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.057 ms
2017-03-01 16:27:57:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.044 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.094 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.088 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.056 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.076 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.037 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.068 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.081 ms
2017-03-01 16:27:58:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.056 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.068 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.078 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.090 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.078 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.102 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.046 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.077 ms
2017-03-01 16:27:59:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.051 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.055 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.087 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.066 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.056 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.055 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.063 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.057 ms
2017-03-01 16:28:00:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=5 ttl=64 time=0.061 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.084 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.075 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.105 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.088 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.068 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.056 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.077 ms
2017-03-01 16:28:01:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=6 ttl=64 time=0.053 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.099 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.094 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.089 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.110 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.060 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.108 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.090 ms
2017-03-01 16:28:02:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=7 ttl=64 time=0.107 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.103 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.122 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.149 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.111 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.084 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.092 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.131 ms
2017-03-01 16:28:03:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=8 ttl=64 time=0.495 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.144 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.041/0.091/0.144/0.029 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.086 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.056/0.086/0.103/0.014 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.117 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.038/0.079/0.117/0.022 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.124 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.047/0.092/0.149/0.030 ms
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.050 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.103 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.036/0.056/0.084/0.016 ms
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.055/0.079/0.108/0.018 ms
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.070 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.052/0.078/0.131/0.023 ms
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: STDOUT: 64 bytes from 127.0.0.1: icmp_seq=9 ttl=64 time=0.070 ms
2017-03-01 16:28:04:  0: STDOUT: --- localhost ping statistics ---
2017-03-01 16:28:04:  0: STDOUT: 10 packets transmitted, 10 packets received, 0.0% packet loss
2017-03-01 16:28:04:  0: STDOUT: round-trip min/avg/max/stddev = 0.051/0.106/0.495/0.130 ms
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:04:  0: INFO: Process exit code: 0
2017-03-01 16:28:05:  0: INFO: Process exit code: 0

=======================================================
Details
=======================================================
Date: 03/01/2017 16:28:05
Frames: 1
Job Submit Date: 02/09/2017 12:19:12
Job User: edwin.amsler
Average RAM Usage: 450560 (1%)
Peak RAM Usage: 450560 (1%)
Average CPU Usage: 0%
Peak CPU Usage: 0%
Used CPU Clocks (x10^6 cycles): 0
Total CPU Clocks (x10^6 cycles): 24552

=======================================================
Slave Information
=======================================================
Slave Name: Edwin's Computer of Awesome
Version: v8.0.17.0 Release (e98b3061f)
Operating System: Mac OS X 10.12.3
Machine User: edwin.amsler
IP Address: 192.168.2.206
MAC Address: de:ad:be:ef:13:37
CPU Architecture: x86_64
CPUs: 8
CPU Usage: 96%
Memory Usage: 9.0 GB / 16.0 GB (56%)
Free Disk Space: 4.419 GB 
Video Card: Intel Iris Pro

Render thread 1:

=======================================================
Log
=======================================================
2017-03-01 16:27:55:  BEGIN - Mobile-029.local\edwin.amsler
2017-03-01 16:27:55:  1: Start Job timeout is disabled.
2017-03-01 16:27:55:  1: Task timeout is disabled.
2017-03-01 16:27:55:  1: Loaded job: Batch Bug Test (589cb2a0e9faf16a26bbcc35)
2017-03-01 16:27:55:  1: Plugin rendering frame(s): 2

=======================================================
Details
=======================================================
Date: 03/01/2017 16:28:05
Frames: 2
Job Submit Date: 02/09/2017 12:19:12
Job User: edwin.amsler
Average RAM Usage: 450560 (1%)
Peak RAM Usage: 450560 (1%)
Average CPU Usage: 0%
Peak CPU Usage: 0%
Used CPU Clocks (x10^6 cycles): 0
Total CPU Clocks (x10^6 cycles): 24288

=======================================================
Slave Information
=======================================================
Slave Name: Slave Name: Edwin's Computer of Awesome
Version: v8.0.17.0 Release (e98b3061f)
Operating System: Mac OS X 10.12.3
Machine User: edwin.amsler
IP Address: 192.168.2.206
MAC Address: de:ad:be:ef:13:37
CPU Architecture: x86_64
CPUs: 8
CPU Usage: 96%
Memory Usage: 9.0 GB / 16.0 GB (56%)
Free Disk Space: 4.419 GB 
Video Card: Intel Iris Pro

We have someone assigned to this, and I’ll keep in contact and help him fix it.

Alcium · August 9, 2018, 3:18pm

Digging out the dust

To get fusion log back in Deadline :
In Plugins/FusionCmd/FusionCmd.py
Replace [code]
self.HideDosWindow = False # This needs to be off so that we can get stdout from ConsoleSlave

    # Set the stdout handlers.

[/code]

With

        self.HideDosWindow = False # This needs to be off so that we can get stdout from ConsoleSlave
        self.CreateNewConsole = True
        
        # Set the stdout handlers.

Can this be pushed to the next release of Deadline please

Thanks

eamsler · August 10, 2018, 5:57pm

It won’t make it into SP19, but I’ve asked the dev team about SP20.