Windows Path Mapping issue (Houdini)


#1

Hi All,

I’ve run into this little problem with Deadline on Houdini, I put path mapping on to enable mac and pc to live together in harmony and the windows machine successfully opens the file from the remapped path, but the output of the file seems to ignore the remapping and files always get rendered directly to the Windows C drive.
Does anyone know what might cause that? Are there any workarounds?

Thanks,
Pete


#2

Is it set in the override options within Deadline? or in the ROP nodes? Are you able to do a simple test scene and submit the task report?


#3

Is the Houdini scene configured to save to “C:”? Usually we’d expect you to be saving to some network path.

Can you post a screenshot of your path mapping and where the output is set to save in Houdini?


#4

Hey thanks for the reply’s,
Ahh, The mac is rendering to a local folder and maybe that’s where the issues is coming from,

Remapped with this -

The thing that I think was confusing me was that the file open remapped fine on the widows machine, but then it renders locally. So I guess it’s able to see that it should remap the file path but not the output?

Thanks,
Pete


#5

Hmm, I also tried this -

And again, it loads fine on the windows box, but saves it to a directory on C: named Volumes/Mac…


#6

That’s interesting… Everything looks fine there to me. I assumed it was just the path mappings that were somewhat off… Is the path it trying to save to “C:\Volumes” or similar? Fun wrinkle with Windows is that if you provide it a path like “\blah” it will assume you’d like to use the active drive (a holdover from DOS).

I think the last data point we’re going to need here is a job report. It’ll have information like your machine’s name and IP address, as well as scene info, so feel free to audit things your not comfortable the public seeing.

In particular, I’m looking for any lines having to do with path mapping or any paths listed. The “Replace Path” should be matching here…


#7

Okay I attached the task report (I couldn’t upload the txt so I hope it’s okay to just paste here).
I just realised this is running on Houdini 17 Hopefully I haven’t just broken something on setting it up :grimacing:
Let me know if you see anything that looks out of place,

Thanks!
Pete

=======================================================
Log
=======================================================
2018-11-08 09:07:06:  0: Loading Job's Plugin timeout is Disabled
2018-11-08 09:07:09:  0: Executing plugin command of type 'Sync Files for Job'
2018-11-08 09:07:09:  0: All job files are already synchronized
2018-11-08 09:07:09:  0: Synchronizing Plugin Houdini from W:/DeadlineRepository\plugins\Houdini took: 0 seconds
2018-11-08 09:07:09:  0: Done executing plugin command of type 'Sync Files for Job'
2018-11-08 09:07:10:  0: Executing plugin command of type 'Initialize Plugin'
2018-11-08 09:07:10:  0: INFO: Executing plugin script 'C:\Users\indi\AppData\Local\Thinkbox\Deadline10\slave\MACHINENAME\plugins\5be36200abe50b070be77bb2\Houdini.py'
2018-11-08 09:07:10:  0: INFO: About: Houdini Plugin for Deadline
2018-11-08 09:07:10:  0: INFO: Render Job As User disabled, running as current user 'indi'
2018-11-08 09:07:10:  0: INFO: The job's environment will be merged with the current environment before rendering
2018-11-08 09:07:10:  0: Done executing plugin command of type 'Initialize Plugin'
2018-11-08 09:07:10:  0: Start Job timeout is disabled.
2018-11-08 09:07:10:  0: Task timeout is disabled.
2018-11-08 09:07:10:  0: Loaded job: Materials_RandomTests - /obj/Turnaround/Render/mantra1 (5be36200abe50b070be77bb2)
2018-11-08 09:07:11:  0: Executing plugin command of type 'Start Job'
2018-11-08 09:07:11:  0: INFO: Executing global asset transfer preload script 'C:\Users\indi\AppData\Local\Thinkbox\Deadline10\slave\MACHINENAME\plugins\5be36200abe50b070be77bb2\GlobalAssetTransferPreLoad.py'
2018-11-08 09:07:11:  0: INFO: Looking for AWS Portal File Transfer...
2018-11-08 09:07:11:  0: INFO: Looking for File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py...
2018-11-08 09:07:11:  0: INFO: Could not find AWS Portal File Transfer.
2018-11-08 09:07:11:  0: INFO: AWS Portal File Transfer is not installed on the system.
2018-11-08 09:07:11:  0: Done executing plugin command of type 'Start Job'
2018-11-08 09:07:11:  0: Plugin rendering frame(s): 1
2018-11-08 09:07:11:  0: Executing plugin command of type 'Render Task'
2018-11-08 09:07:11:  0: INFO: Set HOUDINI_PATHMAP to {"/Users/indi/Dev/Apps/SP/":"X:/", "/Volumes/Macintosh HD/Houdini/":"H:/"}
2018-11-08 09:07:11:  0: INFO: Redshift Path Mapping...
2018-11-08 09:07:11:  0: INFO: source: "/Users/indi/Dev/Apps/SP/" dest: "X:\"
2018-11-08 09:07:11:  0: INFO: source: "/Volumes/Macintosh HD/Houdini/" dest: "H:\"
2018-11-08 09:07:11:  0: INFO: [REDSHIFT_PATHOVERRIDE_FILE] now set to: "C:\Users\indi\AppData\Local\Thinkbox\Deadline10\slave\MACHINENAME\jobsData\5be36200abe50b070be77bb2\RSMapping_temp6BKpR0\RSMapping.txt"
2018-11-08 09:07:11:  0: INFO: Starting Houdini Job
2018-11-08 09:07:11:  0: INFO: Stdout Redirection Enabled: True
2018-11-08 09:07:11:  0: INFO: Stdout Handling Enabled: True
2018-11-08 09:07:11:  0: INFO: Popup Handling Enabled: True
2018-11-08 09:07:11:  0: INFO: QT Popup Handling Enabled: False
2018-11-08 09:07:11:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2018-11-08 09:07:11:  0: INFO: Using Process Tree: True
2018-11-08 09:07:11:  0: INFO: Hiding DOS Window: True
2018-11-08 09:07:11:  0: INFO: Creating New Console: False
2018-11-08 09:07:11:  0: INFO: Running as user: indi
2018-11-08 09:07:11:  0: INFO: Executable: "C:\Program Files\Side Effects Software\Houdini 17.0.352\bin\Hython.exe"
2018-11-08 09:07:11:  0: CheckPathMapping: Swapped "/Volumes/Macintosh HD/Houdini/Scenes/Tests/Materials_RandomTests.hiplc" with "H:\Scenes/Tests/Materials_RandomTests.hiplc"
2018-11-08 09:07:11:  0: INFO: Argument: "C:\Users\indi\AppData\Local\Thinkbox\Deadline10\slave\MACHINENAME\plugins\5be36200abe50b070be77bb2\hrender_dl.py" -f 1 1 1 -g -d /obj/Turnaround/Render/mantra1 "H:/Scenes/Tests/Materials_RandomTests.hiplc"
2018-11-08 09:07:11:  0: INFO: Full Command: "C:\Program Files\Side Effects Software\Houdini 17.0.352\bin\Hython.exe" "C:\Users\indi\AppData\Local\Thinkbox\Deadline10\slave\MACHINENAME\plugins\5be36200abe50b070be77bb2\hrender_dl.py" -f 1 1 1 -g -d /obj/Turnaround/Render/mantra1 "H:/Scenes/Tests/Materials_RandomTests.hiplc"
2018-11-08 09:07:11:  0: INFO: Startup Directory: "C:\Program Files\Side Effects Software\Houdini 17.0.352\bin"
2018-11-08 09:07:11:  0: INFO: Process Priority: BelowNormal
2018-11-08 09:07:11:  0: INFO: Process Affinity: default
2018-11-08 09:07:11:  0: INFO: Process is now running
2018-11-08 09:07:21:  0: STDOUT: Detected Houdini version: (17, 0, 352)
2018-11-08 09:07:21:  0: STDOUT: ['C:\\Users\\indi\\AppData\\Local\\Thinkbox\\Deadline10\\slave\\MACHINENAME\\plugins\\5be36200abe50b070be77bb2\\hrender_dl.py', '-f', '1', '1', '1', '-g', '-d', '/obj/Turnaround/Render/mantra1', 'H:/Scenes/Tests/Materials_RandomTests.hiplc']
2018-11-08 09:07:21:  0: STDOUT: Start: 1
2018-11-08 09:07:21:  0: STDOUT: End: 1
2018-11-08 09:07:21:  0: STDOUT: Increment: 1
2018-11-08 09:07:21:  0: STDOUT: Ignore Inputs: True
2018-11-08 09:07:21:  0: STDOUT: No output specified. Output will be handled by the driver
2018-11-08 09:07:21:  0: STDOUT: Driver: /obj/Turnaround/Render/mantra1
2018-11-08 09:07:21:  0: STDOUT: Input File: H:/Scenes/Tests/Materials_RandomTests.hiplc
2018-11-08 09:07:27:  0: STDOUT: ROP type: ifd
2018-11-08 09:07:27:  0: STDOUT: Enabled Alfred style progress
2018-11-08 09:07:27:  0: STDOUT: Set verbosity to 3
2018-11-08 09:07:27:  0: STDOUT: Rendering frame 1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]   reading geometry from C:/Users/indi/AppData/Local/Temp/houdini_temp/ifds/storage/13788_Materials_RandomTests.1_000_0016.bgeo.sc
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] /obj/sphere_object1/sphere1(loading...)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Deleting temporary geometry 'C:/Users/indi/AppData/Local/Temp/houdini_temp/ifds/storage/13788_Materials_RandomTests.1_000_0016.bgeo.sc'
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]   reading geometry from C:/Users/indi/AppData/Local/Temp/houdini_temp/ifds/storage/13788_Materials_RandomTests.1_000_0077.bgeo.sc
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] /obj/Turnaround/Background/output1(loading...)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Deleting temporary geometry 'C:/Users/indi/AppData/Local/Temp/houdini_temp/ifds/storage/13788_Materials_RandomTests.1_000_0077.bgeo.sc'
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Generating Image: /Volumes/Macintosh HD/Houdini/Renders/Materials_RandomTests/Materials_RandomTests.0001.exr (1280x720)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[C]: 0 Cf+Af[4] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector4
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[Pz]: 0 Pz[1] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  minmax omedian
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     float
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_diffuse]: 0 indirect_diffuse[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_reflect]: 0 indirect_reflect[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_coat]: 0 indirect_coat[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_refract]: 0 indirect_refract[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_volume]: 0 indirect_volume[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[indirect_sss]: 0 indirect_sss[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_diffuse]: 0 direct_diffuse[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_reflect]: 0 direct_reflect[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_coat]: 0 direct_coat[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_refract]: 0 direct_refract[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_volume]: 0 direct_volume[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[direct_sss]: 0 direct_sss[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  gaussian -w 2
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[P]: 0 P[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  minmax omedian
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     vector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]  Plane[N]: 0 N[3] (16-bit float)
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     SampleFilter: alpha
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     PixelFilter:  minmax omedian
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     VEX Type:     unitvector
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gamma:        1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Dither:       0.5
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     Gain:         1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37]     White point:  1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Will be writing to checkpoint file /Volumes/Macintosh HD/Houdini/Renders/Materials_RandomTests/Materials_RandomTests.0001.exr.mantra_checkpoint
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Load Time:
2018-11-08 09:07:37:  0: STDOUT: Frame Wall Clock Time: 0:00:00.56
2018-11-08 09:07:37:  0: STDOUT: Total Wall Clock Time: 0:00:00.56
2018-11-08 09:07:37:  0: STDOUT:        Total CPU Time: 0:00:14.59
2018-11-08 09:07:37:  0: STDOUT:  System CPU Time Only: 0:00:08.34
2018-11-08 09:07:37:  0: STDOUT:     Peak Memory Usage: 130.92 MB
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] VEX Shaders Loaded:
2018-11-08 09:07:37:  0: STDOUT:   op:_auto_/obj/Turnaround/Background/matnet1/shadowmatte1 op:_auto_/obj/Turnaround/Background/matnet1/shadowmatte1
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] 
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] Thread Count: 3
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] mantra: Variable indirect_comp (vector) not found for export in shader 'arealight'
2018-11-08 09:07:37:  0: STDOUT: [09:07:37] mantra: Variable direct_comp (vector) not found for export in shader 'arealight'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable indirect_comp (vector) not found for export in shader 'mat_defaultshader1'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable direct_comp (vector) not found for export in shader 'mat_defaultshader1'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable indirect_comp (vector) not found for export in shader 'op:_auto_/obj/Turnaround/Background/matnet1/shadowmatte1'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable direct_comp (vector) not found for export in shader 'op:_auto_/obj/Turnaround/Background/matnet1/shadowmatte1'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable indirect_comp (vector) not found for export in shader 'mat_principledshader1'
2018-11-08 09:07:39:  0: STDOUT: [09:07:39] mantra: Variable direct_comp (vector) not found for export in shader 'mat_principledshader1'
2018-11-08 09:07:39:  0: STDOUT: ALF_PROGRESS 0%
2018-11-08 09:07:39:  0: STDOUT: ALF_PROGRESS 1%
2018-11-08 09:07:39:  0: STDOUT: ALF_PROGRESS 2%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 3%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 4%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 5%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 6%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 7%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 8%
2018-11-08 09:07:40:  0: STDOUT: ALF_PROGRESS 9%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 10%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 11%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 12%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 13%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 14%
2018-11-08 09:07:41:  0: STDOUT: ALF_PROGRESS 15%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 16%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 17%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 18%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 19%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 20%
2018-11-08 09:07:42:  0: STDOUT: ALF_PROGRESS 21%
2018-11-08 09:07:43:  0: STDOUT: ALF_PROGRESS 22%
2018-11-08 09:07:43:  0: STDOUT: ALF_PROGRESS 23%
2018-11-08 09:07:43:  0: STDOUT: ALF_PROGRESS 24%
2018-11-08 09:07:48:  0: STDOUT: ALF_PROGRESS 25%
2018-11-08 09:07:48:  0: STDOUT: ALF_PROGRESS 26%
2018-11-08 09:07:48:  0: STDOUT: ALF_PROGRESS 27%
2018-11-08 09:07:49:  0: STDOUT: ALF_PROGRESS 28%
2018-11-08 09:07:49:  0: STDOUT: ALF_PROGRESS 29%
2018-11-08 09:07:49:  0: STDOUT: ALF_PROGRESS 30%
2018-11-08 09:07:50:  0: STDOUT: ALF_PROGRESS 31%
2018-11-08 09:07:50:  0: STDOUT: ALF_PROGRESS 32%
2018-11-08 09:07:50:  0: STDOUT: ALF_PROGRESS 33%
2018-11-08 09:07:51:  0: STDOUT: ALF_PROGRESS 34%
2018-11-08 09:07:51:  0: STDOUT: ALF_PROGRESS 35%
2018-11-08 09:07:52:  0: STDOUT: ALF_PROGRESS 36%
2018-11-08 09:07:52:  0: STDOUT: ALF_PROGRESS 37%
2018-11-08 09:07:52:  0: STDOUT: ALF_PROGRESS 38%
2018-11-08 09:07:53:  0: STDOUT: ALF_PROGRESS 39%
2018-11-08 09:07:53:  0: STDOUT: ALF_PROGRESS 40%
2018-11-08 09:07:54:  0: STDOUT: ALF_PROGRESS 41%
2018-11-08 09:07:54:  0: STDOUT: ALF_PROGRESS 42%
2018-11-08 09:07:55:  0: STDOUT: ALF_PROGRESS 43%
2018-11-08 09:07:55:  0: STDOUT: ALF_PROGRESS 44%
2018-11-08 09:07:56:  0: STDOUT: ALF_PROGRESS 45%
2018-11-08 09:07:56:  0: STDOUT: ALF_PROGRESS 46%
2018-11-08 09:07:56:  0: STDOUT: ALF_PROGRESS 47%
2018-11-08 09:07:57:  0: STDOUT: ALF_PROGRESS 48%
2018-11-08 09:07:57:  0: STDOUT: ALF_PROGRESS 49%
2018-11-08 09:07:58:  0: STDOUT: ALF_PROGRESS 50%
2018-11-08 09:07:58:  0: STDOUT: ALF_PROGRESS 51%
2018-11-08 09:07:59:  0: STDOUT: ALF_PROGRESS 52%
2018-11-08 09:07:59:  0: STDOUT: ALF_PROGRESS 53%
2018-11-08 09:08:00:  0: STDOUT: ALF_PROGRESS 54%
2018-11-08 09:08:00:  0: STDOUT: ALF_PROGRESS 55%
2018-11-08 09:08:01:  0: STDOUT: ALF_PROGRESS 56%
2018-11-08 09:08:01:  0: STDOUT: ALF_PROGRESS 57%
2018-11-08 09:08:02:  0: STDOUT: ALF_PROGRESS 58%
2018-11-08 09:08:02:  0: STDOUT: ALF_PROGRESS 59%
2018-11-08 09:08:03:  0: STDOUT: ALF_PROGRESS 60%
2018-11-08 09:08:03:  0: STDOUT: ALF_PROGRESS 61%
2018-11-08 09:08:03:  0: STDOUT: ALF_PROGRESS 62%
2018-11-08 09:08:04:  0: STDOUT: ALF_PROGRESS 63%
2018-11-08 09:08:04:  0: STDOUT: ALF_PROGRESS 64%
2018-11-08 09:08:05:  0: STDOUT: ALF_PROGRESS 65%
2018-11-08 09:08:05:  0: STDOUT: ALF_PROGRESS 66%
2018-11-08 09:08:06:  0: STDOUT: ALF_PROGRESS 67%
2018-11-08 09:08:06:  0: STDOUT: ALF_PROGRESS 68%
2018-11-08 09:08:06:  0: STDOUT: ALF_PROGRESS 69%
2018-11-08 09:08:07:  0: STDOUT: ALF_PROGRESS 70%
2018-11-08 09:08:07:  0: STDOUT: ALF_PROGRESS 71%
2018-11-08 09:08:08:  0: STDOUT: ALF_PROGRESS 72%
2018-11-08 09:08:08:  0: STDOUT: ALF_PROGRESS 73%
2018-11-08 09:08:08:  0: STDOUT: ALF_PROGRESS 74%
2018-11-08 09:08:09:  0: STDOUT: ALF_PROGRESS 75%
2018-11-08 09:08:09:  0: STDOUT: ALF_PROGRESS 76%
2018-11-08 09:08:09:  0: STDOUT: ALF_PROGRESS 77%
2018-11-08 09:08:10:  0: STDOUT: ALF_PROGRESS 78%
2018-11-08 09:08:10:  0: STDOUT: ALF_PROGRESS 79%
2018-11-08 09:08:10:  0: STDOUT: ALF_PROGRESS 80%
2018-11-08 09:08:11:  0: STDOUT: ALF_PROGRESS 81%
2018-11-08 09:08:11:  0: STDOUT: ALF_PROGRESS 82%
2018-11-08 09:08:11:  0: STDOUT: ALF_PROGRESS 83%
2018-11-08 09:08:11:  0: STDOUT: ALF_PROGRESS 84%
2018-11-08 09:08:11:  0: STDOUT: ALF_PROGRESS 85%
2018-11-08 09:08:12:  0: STDOUT: ALF_PROGRESS 86%
2018-11-08 09:08:12:  0: STDOUT: ALF_PROGRESS 87%
2018-11-08 09:08:12:  0: STDOUT: ALF_PROGRESS 88%
2018-11-08 09:08:12:  0: STDOUT: ALF_PROGRESS 89%
2018-11-08 09:08:12:  0: STDOUT: ALF_PROGRESS 90%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 91%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 92%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 93%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 94%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 95%
2018-11-08 09:08:13:  0: STDOUT: ALF_PROGRESS 96%
2018-11-08 09:08:14:  0: STDOUT: ALF_PROGRESS 97%
2018-11-08 09:08:14:  0: STDOUT: ALF_PROGRESS 98%
2018-11-08 09:08:14:  0: STDOUT: ALF_PROGRESS 99%
2018-11-08 09:08:14:  0: STDOUT: ALF_PROGRESS 100%
2018-11-08 09:08:14:  0: STDOUT: [09:08:14] Threading Efficiency: 299.75%
2018-11-08 09:08:14:  0: STDOUT: [09:08:14] Ray Counts:
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]       8,330,436 primary rays          9.04 rays/pixel
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]      15,421,260 occlusion rays       16.73 rays/pixel
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]      20,687,474 opacity rays         22.45 rays/pixel
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]      10,838,665 shading rays         11.76 rays/pixel
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]      55,277,835 total rays           59.98 rays/pixel
2018-11-08 09:08:14:  0: STDOUT: [09:08:14] Unified Cache: 0.00 KB of 3.98 GB used
2018-11-08 09:08:14:  0: STDOUT: [09:08:14] Frame Wall Clock Time: 0:00:36.98
2018-11-08 09:08:14:  0: STDOUT: [09:08:14] Total Wall Clock Time: 0:00:37.54
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]        Total CPU Time: 0:00:17.46
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]  System CPU Time Only: 0:00:09.43
2018-11-08 09:08:14:  0: STDOUT: [09:08:14]     Peak Memory Usage: 537.04 MB
2018-11-08 09:08:17:  0: STDOUT: [09:08:16] Peak Geometry Objects: 4
2018-11-08 09:08:17:  0: STDOUT: [09:08:16] VEX module cache misses: 0
2018-11-08 09:08:17:  0: STDOUT: Finished Rendering
2018-11-08 09:08:17:  0: INFO: Process exit code: 0
2018-11-08 09:08:17:  0: INFO: Finished Houdini Job
2018-11-08 09:08:17:  0: Done executing plugin command of type 'Render Task'

=======================================================
Details
=======================================================
Date: 11/08/2018 09:08:13
Frames: 1
Job Submit Date: 11/08/2018 09:06:56
Job User: indi
Average RAM Usage: 467226880 (3%)
Peak RAM Usage: 890912768 (6%)
Average CPU Usage: 23%
Peak CPU Usage: 75%
Used CPU Clocks (x10^6 cycles): 169027
Total CPU Clocks (x10^6 cycles): 734899

=======================================================
Slave Information
=======================================================
Slave Name: MACHINENAME
Version: v10.0.21.5 Release (7cef63955)
Operating System: Windows 10 Home
Running As Service: No
Machine User: indi
IP Address: -
MAC Address: -
CPU Architecture: x64
CPUs: 4
CPU Usage: 75%
Memory Usage: 4.4 GB / 15.9 GB (27%)
Free Disk Space: 823.215 GB 
Video Card: Radeon (TM) RX 480 Graphics

#8

Hmm… Well, we’re using environment variables to tip Houdini and Redshift that they need to change things:

2018-11-08 09:07:11: 0: INFO: Set HOUDINI_PATHMAP to {"/Users/indi/Dev/Apps/SP/":“X:/”, “/Volumes/Macintosh HD/Houdini/”:“H:/”}
2018-11-08 09:07:11: 0: INFO: Redshift Path Mapping…
2018-11-08 09:07:11: 0: INFO: source: “/Users/indi/Dev/Apps/SP/” dest: “X:”
2018-11-08 09:07:11: 0: INFO: source: “/Volumes/Macintosh HD/Houdini/” dest: “H:”

And we’re swapping one path ourselves here:

2018-11-08 09:07:11: 0: CheckPathMapping: Swapped “/Volumes/Macintosh HD/Houdini/Scenes/Tests/Materials_RandomTests.hiplc” with “H:\Scenes/Tests/Materials_RandomTests.hiplc”

And yet, it doesn’t seem to stick:

2018-11-08 09:07:37: 0: STDOUT: [09:07:37] Generating Image: /Volumes/Macintosh HD/Houdini/Renders/Materials_RandomTests/Materials_RandomTests.0001.exr (1280x720)
2018-11-08 09:07:37: 0: STDOUT: [09:07:37] Will be writing to checkpoint file /Volumes/Macintosh HD/Houdini/Renders/Materials_RandomTests/Materials_RandomTests.0001.exr.mantra_checkpoint

One workaround is to use “$HIP” in your file paths which references where the Houdini scene file lives. In the case of your EXR it should be $HIP/../../Renders/Materials_RandomTests/Materials_RandomTests.0001.exr.mantra_checkpoint.

The benefit of that workflow is it will work across platforms without Deadline. Even still, these paths should have remapped for you…


#9

Thanks @eamsler, yeah that seems super strange. The fact that it is loading from the correct path too.
I’d usually prefer to keep the renders in a different location to the rest of the project but it outputting to $HIP seems to work so that’s great!


#10

Hey Pete,

Glad you’re able to render using the $HIP variable.

This appears to be a bug on our end not properly passing the output path to the plugin when performing the pathmapping. We’re actually expecting a path on the plugin side but aren’t receiving it, as such there’s no pathmapping occurring on it.

0: STDOUT: No output specified. Output will be handled by the driver

This issue has actually flown under the radar for a while now, since I believe most users use the $HIP variable or something like it. Pretty certain it’s been like this since we did our big Houdini re-factor a while back.

I’ve logged an internal issue to properly pass along that output file path so that we can perform path mapping on whatever path users want to put there (ie. like your preferred workflow).

Cheers
Morgan


#11

Hey thanks for the update @mepp!


#12

Ahh damn! This seems to extend to image files which makes it pretty tricky, since I almost always keep them in a totally separate folder :frowning:
Also $JOB doesn’t seem to help. Just wondering, is there going to be a fix for this?
Thanks,
Pete


#13

It’s been logged but considering there’s a workflow that can get most unstuck it’s likely not going to get much love.

The separate folder should be fine. Could you give a fake example of where the HIP and where the output live? I can probably help. Really, to jump up a folder you just use “/…/” in the path.


#14

Hey, good to know it’s on the radar :slight_smile:
I’ve gone through and made sure all paths are now relative to the $HIP and it seems to be working now. It’s not too much of a hassle now that I have my head around it and it might actually make the project more portable anyway.

Thanks!
Pete


#15

We are having huge issues rendering cross platform from Houdini on Deadline.
Submitting from windows and rendering on a linux node.

The path remapping works fine for the inital .hip file.
But the render directory or render doesn’t show up at all.

Although Deadline marks the task as complete. It puts out an error that is not caught:
STDOUT: [Redshift] Output file locked or path not available for writing. Skipping frame. (X:/17110093_achthamburg_researchanddevelopment/06_vfx/01_seq/deadline_test_190208/houdini/render/win2linux_env_job/deadline_test_190208_v001.Redshift_win2lin.0002.exr.lock)

That tell sme that the X: network drive didn’t got remapped and the linux node is trying to write to a wrong location and thus not generating an output.

Is there a way to declare a Output locatiohoudini.zip (2.5 MB)
n on submission so that Deadline doesn’t get the wrong path from the driver?


#16

Hey @martin_acht, that sounds like there might be a permissions issue writing to that file. Can you test it out and write some other file to the location from the slave machine?
I had a lot of issues with permissions going from PC to mac


#17

Will do as soon as i can get to it. The rendernode is temporarily in windows for an urgent job. Will test and report in a few days. Thanks for the suggestion