RealisticRendering takes forever to Launch on 4.11.1

anonymous_user_af11fb2b · April 17, 2016, 9:05am

I submit this as a bug, because sample is made by Epic Games.

How to reproduce the bug:

Download UE 4.11.1
Download Realistic Rendering by Epic Games sample from marketplace
Press “Launch” in the Editor.

I waited 12 hours, nothing happenned, CPU usage was 50%.

Bug is reproducable on fresh new copies of Editor and Sample. I did no changes to project.

Here is log that I got:

LogPlatformFile: Not using cached read wrapper
LogInit:Display: RandInit(593185711) SRandInit(593185715).
LogTaskGraph: Started task graph with 4 named threads and 5 total threads.
LogStats: Stats thread started at 0.099328
LogInit: Version: 4.11.1-2934540+++UE4+Release-4.11
LogInit: API Version: 2927265
LogInit: Compiled (64-bit): Apr  6 2016 02:14:16
LogInit: Compiled with Visual C++: 19.00.23026.00
LogInit: Build Configuration: Development
LogInit: Branch Name: ++UE4+Release-4.11
LogInit: Command line:  
LogInit: Base directory: C:/Program Files (x86)/Epic Games/4.11/Engine/Binaries/Win64/
LogInit: Installed Engine Build: 1
LogInit: Using libcurl 7.41.0
LogInit:  - built for x86_64-pc-win32
LogInit:  - supports SSL with WinSSL
LogInit:  - other features:
LogInit:      CURL_VERSION_SSL
LogInit:      CURL_VERSION_IPV6
LogInit:      CURL_VERSION_ASYNCHDNS
LogInit:      CURL_VERSION_LARGEFILE
LogInit:      CURL_VERSION_IDN
LogInit:  CurlRequestOptions (configurable via config and command line):
LogInit:  - bVerifyPeer = true  - Libcurl will verify peer certificate
LogInit:  - bUseHttpProxy = false  - Libcurl will NOT use HTTP proxy
LogInit:  - bDontReuseConnections = false  - Libcurl will reuse connections
LogInit:  - CertBundlePath = nullptr  - Libcurl will use whatever was configured at build time.
LogInit: Presizing for max 16777216 objects, including 0 objects not considered by GC, pre-allocating 0 bytes for permanent pool.
LogInit: Object subsystem initialized
LogInit: Selected Device Profile: [Windows]
LogInit: Applying CVar settings loaded from the selected device profile: [Windows]
LogInit: Computer: HUGOPC
LogInit: User: Roman
LogInit: CPU Page size=65536, Cores=2
LogInit: High frequency timer resolution =2.942982 MHz
LogMemory: Memory total: Physical=10.0GB (10GB approx)
LogMemory: Platform Memory Stats for Windows
LogMemory: Process Physical Memory: 309.86 MB used, 309.86 MB peak
LogMemory: Process Virtual Memory: 314.32 MB used, 314.32 MB peak
LogMemory: Physical Memory: 4958.63 MB used, 10238.49 MB total
LogMemory: Virtual Memory: 628.59 MB used, 134217728.00 MB total
LogTextLocalizationManager: No specific translations for ('en-US') exist, so ('en') translations will be used.
LogD3D11RHI: D3D11 adapters:
LogD3D11RHI:    0. 'NVIDIA GeForce GTX 650' (Feature Level 11_0)
LogD3D11RHI:       964MB of dedicated video memory, 0MB of dedicated system memory, and 3072MB of shared system memory, 2 output[s]
LogD3D11RHI:    1. 'Microsoft Basic Render Driver' (Feature Level 11_0)
LogD3D11RHI:       0MB of dedicated video memory, 0MB of dedicated system memory, and 256MB of shared system memory, 0 output[s]
LogD3D11RHI: Chosen D3D11 Adapter: 0
LogD3D11RHI: Creating new Direct3DDevice
LogD3D11RHI:     Adapter Name: NVIDIA GeForce GTX 650
LogD3D11RHI:   Driver Version: 10.18.13.6491 (internal 10.18.13.6491)
LogD3D11RHI:      Driver Date: 4-8-2016
LogRHI: Texture pool is 675 MB (70% of 964 MB)
LogD3D11RHI: Async texture creation enabled
LogShaderCompilers: Guid format shader working directory is -31 characters bigger than the processId version (../../../../../../Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Intermediate/Shaders/WorkingDirectory/7028/).
LogShaderCompilers: Cleaned the shader compiler working directory 'C:/Users/Roman/AppData/Local/Temp/UnrealShaderWorkingDir/36A5B8F84F631BE785F9D3A76B567B70/'.
LogShaderCompilers:Display: Using Local Shader Compiler.
LogTemp:Display: Loaded TP AllDesktopTargetPlatform
LogTemp:Display: Loaded TP WindowsClientTargetPlatform
LogTemp:Display: Loaded TP WindowsNoEditorTargetPlatform
LogTemp:Display: Loaded TP WindowsServerTargetPlatform
LogTemp:Display: Loaded TP WindowsTargetPlatform
LogTemp:Display: Loaded TP AndroidTargetPlatform
LogTemp:Display: Loaded TP Android_ASTCTargetPlatform
LogTemp:Display: Loaded TP Android_ATCTargetPlatform
LogTemp:Display: Loaded TP Android_DXTTargetPlatform
LogTemp:Display: Loaded TP Android_ETC1TargetPlatform
LogTemp:Display: Loaded TP Android_ETC2TargetPlatform
LogTemp:Display: Loaded TP Android_MultiTargetPlatform
LogTemp:Display: Loaded TP Android_PVRTCTargetPlatform
LogTemp:Display: Loaded TP HTML5TargetPlatform
LogTemp:Display: Loaded TP IOSTargetPlatform
LogTemp:Display: Loaded TP TVOSTargetPlatform
LogTemp:Display: Loaded TP LinuxNoEditorTargetPlatform
LogTemp:Display: Loaded TP LinuxServerTargetPlatform
LogTemp:Display: Loaded TP LinuxTargetPlatform
LogTargetPlatformManager:Display: Building Assets For Windows
LogDerivedDataCache:Display: Max Cache Size: 512 MB
LogDerivedDataCache: Loaded boot cache 0.20s 46MB C:/Users/Roman/AppData/Local/UnrealEngine/4.11/DerivedDataCache/Boot.ddc.
LogDerivedDataCache:Display: Loaded Boot cache: C:/Users/Roman/AppData/Local/UnrealEngine/4.11/DerivedDataCache/Boot.ddc
LogDerivedDataCache: FDerivedDataBackendGraph:  Pak pak cache file ../../../../../../Users/Roman/Documents/Unreal Projects/NewRealisticRendering/DerivedDataCache/DDC.ddp not found, will not use a pak cache.
LogDerivedDataCache: Unable to find inner node Pak for hierarchical cache Hierarchy.
LogDerivedDataCache:Display: Pak cache opened for reading ../../../../../../Users/Roman/Documents/Unreal Projects/NewRealisticRendering/DerivedDataCache/Compressed.ddp.
LogDerivedDataCache:Display: Pak cache opened for reading ../../../Engine/DerivedDataCache/Compressed.ddp.
LogDerivedDataCache: Using Local data cache path C:/Users/Roman/AppData/Local/UnrealEngine/Common/DerivedDataCache: Writable
LogInit: Selected Device Profile: [Windows]
LogContentStreaming: Texture pool size is 0.00 MB
LogMaterial: Uniform references updated for custom material expression Custom.
LogMeshUtilities: No automatic mesh reduction module available
LogMeshUtilities: No automatic mesh merging module available
LogMeshUtilities: No distributed automatic mesh merging module available
LogUObjectArray: 27678 objects as part of root set at end of initial load.
LogUObjectAllocator: 5591392 out of 0 bytes used by permanent object pool.
LogUObjectArray: CloseDisregardForGC: 0/0 objects in disregard for GC pool
LogInit: WinSock: version 1.1 (2.2), MaxSocks=32767, MaxUdp=65467
LogSlate: SlateFontCache - WITH_FREETYPE: 1, WITH_HARFBUZZ: 1
LogSlate: SlateFontCache - WITH_FREETYPE: 1, WITH_HARFBUZZ: 1
LogStreaming:Warning: Failed to read file '../../../Engine/Content/Editor/Slate/Common/Selection_16x.png' error.
LogSlate: Could not find file for Slate resource: ../../../Engine/Content/Editor/Slate/Common/Selection_16x.png
LogStreaming:Warning: Failed to read file '../../../Engine/Content/Editor/Slate/Common/Selection_16x.png' error.
LogSlate: Could not find file for Slate resource: ../../../Engine/Content/Editor/Slate/Common/Selection_16x.png
LogStreaming:Warning: Failed to read file '../../../Engine/Content/Editor/Slate/Common/Selection_16x.png' error.
LogSlate: Could not find file for Slate resource: ../../../Engine/Content/Editor/Slate/Common/Selection_16x.png
LogSubstanceCoreModule: Substance CPU Engine Loaded, Max Texture Size 2048
LogUdpMessaging: Initializing bridge on interface 0.0.0.0:0 to multicast group 230.0.0.1:6666.
LogAssetRegistry: FAssetRegistry took 0.0228 seconds to start up
SourceControl: Info Source control is disabled
SourceControl: Info Source control is disabled
SourceControl: Info Source control is disabled
SourceControl: Info Source control is disabled
LogEngine: Initializing Engine...
LogHMD: SteamVR failed to initialize.  Err: 110
LogHMD: SteamVR failed to initialize.  Err: 110
LogAIModule: Creating AISystem for world NewWorld
LogInit: XAudio2 using 'Speakers (High Definition Audio Device)' : 2 channels at 44.1 kHz using 16 bits per sample (channel mask 0x3)
LogInit: FAudioDevice initialized.
LogDerivedDataCache: Saved boot cache 0.05s 46MB C:/Users/Roman/AppData/Local/UnrealEngine/4.11/DerivedDataCache/Boot.ddc.
LogInit: Texture streaming: Enabled
LogInit: Transaction tracking system initialized
BlueprintLog: New page: Editor Load
LocalizationService: Info Localization service is disabled
LogCook:Display: Max memory allowance for cook 8192mb
LogCook:Display: Mobile HDR setting 1
LogCook:Display: Done creating registry. It took  0.00s.
LogFileCache: Scanning file cache for directory 'C:/Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Content/' took 0.02s
SourceControl: Info Source control is disabled
Cmd: MAP LOAD FILE="../../../../../../Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Content/Maps/Room.umap" TEMPLATE=0 SHOWPROGRESS=1 FEATURELEVEL=3
LightingResults: New page: Lighting Build
MapCheck: New page: Map Check
LightingResults: New page: Lighting Build
LogTexture:Display: Building textures: HQ_Lightmap0_1 (AutoDXT)
LogTexture:Display: Building textures: HQ_Lightmap0_2 (AutoDXT)
LogTexture:Display: Building textures: HQ_Lightmap0_3 (AutoDXT)
LogTexture:Display: Building textures: HQ_Lightmap0_5 (AutoDXT)
LogTexture:Display: Building textures: HQ_Lightmap0_6 (AutoDXT)
LogTexture:Display: Building textures: LQ_Lightmap_0_1 (DXT1)
LogTexture:Display: Building textures: LQ_Lightmap_0_2 (DXT1)
LogTexture:Display: Building textures: LQ_Lightmap_0_3 (DXT1)
LogTexture:Display: Building textures: LQ_Lightmap_0_5 (DXT1)
LogTexture:Display: Building textures: LQ_Lightmap_0_6 (DXT1)
LogTexture:Display: Building textures: ShadowMapTexture2D_14 (BGRA8)
LogTexture:Display: Building textures: ShadowMapTexture2D_15 (BGRA8)
LogTexture:Display: Building textures: ShadowMapTexture2D_16 (BGRA8)
LogTexture:Display: Building textures: ShadowMapTexture2D_18 (BGRA8)
LogAIModule: Creating AISystem for world Room
LogEditorServer: Finished looking for orphan Actors (0.000 secs)
Cmd: MAP CHECKDEP NOCLEARLOG
MapCheck: Info Map check complete: 0 Error(s), 0 Warning(s), took 130.666ms to complete.
LogFileHelpers: Loading map 'Room' took 14.829
LogCollectionManager: Loaded 0 collections in 0.002252 seconds
LogFileCache: Scanning file cache for directory 'C:/Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Saved/Collections/' took 0.01s
LogFileCache: Scanning file cache for directory 'C:/Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Content/Developers/Roman/Collections/' took 0.02s
LogFileCache: Scanning file cache for directory 'C:/Users/Roman/Documents/Unreal Projects/NewRealisticRendering/Content/Collections/' took 0.01s
LogCollectionManager: Rebuilt the GUID cache for 0 collections in 0.000003 seconds
LogContentBrowser: Native class hierarchy populated in 0.0122 seconds. Added 1994 classes and 349 folders.
LogStats: UGameplayTagsManager::ConstructGameplayTagTree: Construct from data asset -  0.000 s
LogStats: UGameplayTagsManager::ConstructGameplayTagTree: GameplayTagTreeChangedEvent.Broadcast -  0.000 s
LogContentBrowser: Native class hierarchy updated for 'GameplayTags' in 0.0007 seconds. Added 12 classes and 0 folders.
LogContentBrowser: Native class hierarchy updated for 'WidgetCarousel' in 0.0005 seconds. Added 0 classes and 0 folders.
LogContentBrowser: Native class hierarchy updated for 'SuperSearch' in 0.0006 seconds. Added 1 classes and 2 folders.
LogContentBrowser: Native class hierarchy updated for 'AddContentDialog' in 0.0005 seconds. Added 0 classes and 0 folders.
LogContentBrowser: Native class hierarchy updated for 'SceneOutliner' in 0.0005 seconds. Added 1 classes and 2 folders.
LogCrashTracker: Crashtracker disabled due to settings.
LogUObjectGlobals:Warning: Failed to find object 'Class None.'
LogContentBrowser: Native class hierarchy updated for 'HierarchicalLODOutliner' in 0.0006 seconds. Added 1 classes and 2 folders.
LogLoad: Full Startup: 23.62 seconds (BP compile: 0.05 seconds)
LogExternalProfiler: Found external profiler: VSPerf
LogExternalProfiler: Using external profiler: VSPerf
LogWindowsTextInputMethodSystem:Display: IME system now deactivated.
LogOcInput: OculusInput pre-init called
LogContentStreaming: Texture pool size now 1000 MB
LogRenderer: Reallocating scene render targets to support 1080x688 (Frame:2).
LogAssetRegistry: Asset discovery search completed in 17.5734 seconds
LogCollectionManager: Rebuilt the object cache for 0 collections in 0.000001 seconds (found 0 objects)
LogCollectionManager: Fixed up redirectors for 0 collections in 0.000132 seconds (updated 0 objects)
LogPlayLevel: Launcher Device ID: WindowsNoEditor@HUGOPC
LogPlayLevel: PlayLevel: No blueprints needed recompiling
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:Core.System:Paths:../../../Engine/Content ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:Core.System:Paths:../../../Engine/Content ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:Core.System:Paths:../../../Engine/Content ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:Core.System:Paths:../../../Engine/Content ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogCook:Display: Invalidating cooked content because Engine:/Script/EngineSettings.GameMapsSettings:GameInstanceClass:/Script/Engine.GameInstance ini setting was out of date
LogChunkManifestGenerator: bChunkHardReferencesOnly: 0
LogStats:Warning: MetaData mismatch. Did you assign a stat to two groups? New //STATGROUP_Threads//FLauncherTask///Thread_116c_0///####STATCAT_Advanced#### old //STATGROUP_Threads//FLauncherTask///Thread_a08_0///####STATCAT_Advanced####
LogTexture:Warning: Building texture with an invalid GUID: /Engine/Transient.Texture2D_1
LogTexture:Warning: Texture has no source mips: /Engine/Transient.Texture2D_1
LogTexture:Warning: Building texture with an invalid GUID: /Engine/Transient.Texture2D_0
LogTexture:Warning: Texture has no source mips: /Engine/Transient.Texture2D_0
LogTexture:Warning: Texture has no source mips: /Engine/Transient.Texture2D_1
LogTexture:Warning: Texture has no source mips: /Engine/Transient.Texture2D_0

UPDATE 1: DxDiag is attached: DxDiag

UPDATE 2: Also Substance plugin is installed.

anonymous_user_b9532c9a · April 18, 2016, 8:48pm

Hi Karlsson600,

Unfortunately, I have not been able to reproduce this on my end. Can you post a copy of your dxdiag here so I can take a look?

anonymous_user_af11fb2b · April 19, 2016, 6:05am

Thank you very much for your answer. I updated the question with additional details you requested.

anonymous_user_b9532c9a · April 19, 2016, 7:09pm

Are your video card drivers up to date? What scalability settings are you using?

anonymous_user_51ac23c1 · April 25, 2016, 5:56am

Hello, I have the same issuer. Scalability settings are high, and shadows settings are epic. Driver is up to date.

anonymous_user_af11fb2b · April 25, 2016, 9:48am

Yes, card drivers are up to date, scalability settings are epic

anonymous_user_b9532c9a · April 25, 2016, 2:33pm

Have you tried updating to 4.11.2 to see if you get the same error? If you lower the scalability settings do you see any change?

anonymous_user_af11fb2b · April 26, 2016, 11:09pm

Yes, same thing in 4.11.2.

anonymous_user_b9532c9a · April 27, 2016, 3:06pm

I’ve been trying this on my end, however I have not had any success in reproducing this. When I launch, it takes ~2 minutes, which can be attributed to the detailed materials and lighting, and it launches without any problems. Do you have any other programs running when you attempt to launch the demo? Do you see a large memory spike when you attempt to do so?

anonymous_user_b9532c9a · May 2, 2016, 2:27pm

Hi Karlsson600,

We have not heard from you in several days. I am marking this as answered for tracking purposes. If you are still experiencing this error, please comment with the requested information.