-- Log file for all dbTracef messages --

15:45:49.73   NetworkManager::Create - creating network manager
15:45:49.73   Read 269 bytes from network datastore login_cache.bin
15:45:49.73   Read 52 bytes from network datastore global_cache.bin
15:45:49.73   Read 100 bytes from network datastore ping_cache.bin
15:45:49.73   QuazalInitializer - static initializing Quazal library
15:45:49.73   PingCache - populating cache with 4 pings
15:45:49.73   Sent message game CompanyOfHeroes started 1488 602 allowtraffic
15:45:49.73   RemoteDLManager - Connection Restored.
15:45:49.73   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
15:45:49.78   WinTransport - CreateSocket exclusive broadcast socket was available.
15:45:49.78   WinTransport - CreateSocket listening for broadcasts on default port
15:45:49.78   WinTransport - Host Name: NgocQuang-PC, aliases: , type=AF_INET, len=4
15:45:49.78   WinTransport - Host IP Address #0: 5.162.102.231
15:45:49.78   WinTransport - WARNING, MultipleNetwork adapters may cause problems
15:45:49.78   WinTransport - Interface #0: ip:5.162.102.231, broadcast:5.162.102.231, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
15:45:49.78   WinTransport - Interface #1: ip:192.168.1.100, broadcast:192.168.1.100, flags=IFF_BROADCAST IFF_MULTICAST
15:45:49.78   WinTransport - Interface #2: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
15:45:49.78   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
15:45:49.78   WinTransport - Quazal address string = udp:/address=5.162.102.231;port=6112
15:45:49.78   SessionManager - Peer Header Size = 16 bytes
15:45:49.78   SessionManager - Game Data overhead = 7 bytes
15:45:49.78   SessionManager - Proxy overhead = 7 bytes
15:45:49.78   MessageInternal::CreateChannel: Created channel 47535450
15:45:49.78   Session::Initialize - info, initializing session object, using threads.
15:45:49.78   SessionManager::RegisterSession - Registering new session 03cb5b58
15:45:49.78   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
15:45:49.78   AutomatchInternal: Instantiating
15:45:49.78   PartyInternal: Instantiating
15:45:49.78   MessageInternal::CreateChannel: Created channel 50525459
15:45:49.78   MessageInternal::CreateChannel: Created channel 51434b4d
15:45:49.78   Net::ThreadFunction - Entering network thread function...
15:45:49.78   MessageInternal::CreateChannel: Created channel 534d5347
15:45:49.78   MessageInternal::CreateChannel: Created channel 474d4343
15:45:49.78   MessageInternal::CreateChannel: Created channel 474f424a
15:45:49.78   MessageInternal::CreateChannel: Created channel 4d4f444d
15:45:49.78   MessageInternal::CreateChannel: Created channel 53594e43
15:45:49.78   MessageInternal::DestroyChannel: Destroyed channel 534d5347
15:45:49.78   MessageInternal::DestroyChannel: Destroyed channel 474d4343
15:45:49.78   MessageInternal::DestroyChannel: Destroyed channel 474f424a
15:45:49.78   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
15:45:49.78   MessageInternal::DestroyChannel: Destroyed channel 53594e43
15:45:49.79   GAME -- Available memory: 3071MB Physical RAM, 3069MB Pagefile, 4095 Virtual Address Space
15:45:49.79   Transport - Largest received is now 19
15:45:49.79   Transport::OpenInternal request to WINaddr:127.0.0.1:6112;
15:45:49.80   Transport - Largest sent is now 19
15:45:53.16   DLLDriverLinker -- Adding driver 'spDx10.dll'.
15:45:53.17   DLLDriverLinker -- Adding driver 'spDx9.dll'.
15:45:53.17   DLLDriverLinker -- 2 DLL drivers found.
15:45:53.22   SPDx10 -- Adapter [ASUS EAH3450 Series]: 508MB dedicated video memory, 0MB dedicated system memory and 1279MB shared system memory.
15:45:54.94   DLLDriverLinker -- 2 DLL drivers found.
15:45:55.09   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
15:45:55.09   GAME -- Resolution set to 1024x768 (fullscreen).
15:45:55.09   SPDx9 -- Driver Name = atiumdag.dll  Desc = ASUS EAH3450 Series
15:45:55.09   SPDx9 -- Driver Vendor = 0x1002  Device = 0x95C5  SubSys = 0x01E21043  Rev = 0x0000
15:45:55.09   SPDx9 -- Driver Version  Product = 0x0007  Version = 0x000E  SubVersion = 0x00  Build = 5.97
15:45:55.09   SPDx9 -- Driver GUID = {D7B71EE2-D685-11CF-A671-E821A1C2CA35}
15:45:55.59   SPDx9 -- 1774MB available Texture Memory
15:45:55.59   ShaderDatabase: using shader profile [ps30]
15:45:56.16   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
15:45:56.40   GameObjLoader 0c328130 - resetting counters
15:45:56.40   GameObjLoader 0c328130 - Created loader
15:45:56.40   GameObjLoader 0c328290 - resetting counters
15:45:56.40   GameObjLoader 0c328290 - Created loader
15:45:56.71   GAME -- Beginning FE
15:45:56.71   Sent message game CompanyOfHeroes allowtraffic
15:45:56.71   UIFrontEnd - Loading Front End
15:45:56.71   THREAD: Hyper-Threading Technology Processors are not detected.
15:45:56.79   SOUND -- Initializing ...
15:45:57.54   SOUND -- Initialization completed!
15:45:57.54   UIFrontEnd - Initializing Forms
15:45:59.54   CampaignFilter::BindFilterSpecificWidgets()
15:45:59.61   installed_products = ( )
15:45:59.65   Activating screen: AppResolutionPrompt
15:45:59.65   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
15:45:59.65   Activating screen: prompt_generic
15:45:59.65   Activating screen: prompt_generic
15:45:59.65   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
15:45:59.65   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
15:45:59.65   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
15:45:59.65   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
15:46:05.63   Activating screen: AppLoadingForm
15:46:05.63   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
15:46:06.42   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
15:46:06.43   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
15:46:06.43   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
15:46:06.43   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
15:46:06.43   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
15:46:06.44   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
15:46:06.82   GAME -- Using player profile NGOCQUANG-PC
15:46:10.21   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
15:46:11.18   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
15:46:12.24   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
15:46:12.24   RendezvousManager: CreateSession - starting profile=Guest login
15:46:12.29   GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure.
15:46:12.29   RendezvousManager - unable to connect to the server as guest
15:46:12.30   OnConnect: connect failure
15:46:12.30   RendezvousManager::Shutdown - deleting instance
15:46:12.30   RendezvousManager - terminating all server calls in progress
15:46:12.30   CallManager - terminating all server calls in progress (1 in progress)
15:46:12.30   RendezvousManager - destroying chat handler
15:46:12.30   ~RendezvousManager - m_state = 7, no need for server disconnect
15:46:12.32   OnConnect: this wasnt a reconnect, no need for autologin
15:46:12.34   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
15:46:12.34   CRC & Version Info : 0000025a:ce5073b5:ec3c9e0d eastern_front:602:factionfix.dll 1
15:46:12.34   Activating screen: FEMovie
15:46:12.34   Activating screen: OnlineWidget
15:46:12.34   Activating screen: RelicOnlineLogin
15:46:12.39   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
15:46:12.39   You have a valid version of the game.
15:46:12.41   AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now
15:46:12.41   installed_products = ( DLC1 DLC2 DLC3 )
15:46:12.41   OnLogin: no previous login, auto selecting profile not required
15:46:12.41   Activating screen: MessageBoxPopup
15:46:12.41   Activating screen: MessageBoxPopup
15:46:12.41   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
15:46:20.39   Beginning media verification...
15:46:20.39   Validator type : validators\udro\udro.dll
15:46:20.39   Activating screen: MessageBoxPopup
15:46:22.63   Activating screen: FE_mm_01
15:46:22.63   GAME -- Setting campaign state to 'dlc1'
15:46:22.63   GAME -- Closing state 'dlc1'
15:46:22.63   GAME -- Setting campaign state to 'dlc2'
15:46:22.63   GAME -- Closing state 'dlc2'
15:46:22.64   GAME -- Setting campaign state to 'dlc3'
15:46:22.64   GAME -- Closing state 'dlc3'
15:46:25.61   Activating screen: MessageBoxPopup
15:46:25.61   Created Matchinfo
15:46:25.61   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
15:46:25.61   starting LAN hosting
15:46:25.65   OnJoinAdvertisementSuccess - joined online match, server leave notification required
15:46:25.65   starting local hosting
15:46:25.65   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
15:46:25.65   Allocated route ID=0 for PeerID 1 at WINaddr:5.162.102.231:6112;
15:46:25.65   Transport::OpenInternal request to WINaddr:5.162.102.231:6112;
15:46:25.65   Session::Host sid = 5C0180BB4148771B, hostURL = , local addresses = WINaddr:5.162.102.231:6112;
15:46:25.65   ValidateCustomData: called with 217 bytes of custom data
15:46:25.65   Host accepted Peer 1 into the match at address list=WINaddr:5.162.102.231:6112;, routes=WINaddr:5.162.102.231:6112;
15:46:25.65   AdvertisementInternal::Process - EVENT_NEWPEER
15:46:25.65   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
15:46:25.66   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
15:46:25.66   hosting - Session is connected
15:46:25.66   Net::Session::SetVisible - session is set to VISIBLE.
15:46:25.67   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:5.162.102.231:6112; (ping=0ms) 100.00%, pending=0, dead=0
15:46:25.67   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=1/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
15:46:25.68   hosting completed successfully
15:46:25.68   HostAsync - completed with HostResult = 0
15:46:25.68   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
15:46:25.68   Activating screen: OnlineGameSetup
15:46:25.68   MessageInternal::CreateChannel: Created channel 534d5347
15:46:25.68   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
15:46:25.68   MatchInternal::SetMatchType - new type 14 - updating server
15:46:25.68   Transport - Largest received is now 453
15:46:25.69   SetVisible called while !IsConnected
15:46:25.70   GetMaxFrameTimeFromProfile: players=6 expected FPS=18.053484, bars=5, max avg=0.046, sd=0.009, 3 samples =  0.05 0.05 0.03
15:46:25.70   Activating screen: RelicOnlineChat
15:46:25.70   Activating screen: RelicOnlineNewsScreen
15:46:25.70   Activating screen: RelicOnlineStatsScreen
15:46:25.70   Activating screen: Achievements
15:46:25.70   GAME -- Setting campaign state to 'dlc1'
15:46:25.70   GAME -- Closing state 'dlc1'
15:46:25.70   Transport - Largest received is now 509
15:46:25.71   GAME -- Setting campaign state to 'dlc1'
15:46:25.71   GAME -- Closing state 'dlc1'
15:46:25.71   GAME -- Setting campaign state to 'dlc2'
15:46:25.71   GAME -- Closing state 'dlc2'
15:46:25.71   GAME -- Setting campaign state to 'dlc3'
15:46:25.71   GAME -- Closing state 'dlc3'
15:46:25.71   GAME -- Setting campaign state to 'dlc1'
15:46:25.71   GAME -- Closing state 'dlc1'
15:46:25.72   Activating screen: GameHistory
15:46:25.72   GAME -- Setting campaign state to 'dlc1'
15:46:25.72   GAME -- Closing state 'dlc1'
15:46:25.72   Transport - Largest received is now 1166
15:46:25.73   GAME -- Setting campaign state to 'dlc2'
15:46:25.73   GAME -- Closing state 'dlc2'
15:46:25.73   GAME -- Setting campaign state to 'dlc3'
15:46:25.73   GAME -- Closing state 'dlc3'
15:46:25.74   Activating screen: OnlineGameSetup
15:46:25.74   Activating screen: RelicOnlineTabs
15:46:25.74   AutomatchInternal::OnHostComplete - Completed Host with success=1
15:46:25.74   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
15:46:25.74   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
15:46:25.74   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
15:46:25.74   Net::Session::SetVisible - session is set to VISIBLE.
15:46:25.74   GetMaxFrameTimeFromProfile: players=6 expected FPS=18.053484, bars=5, max avg=0.046, sd=0.009, 3 samples =  0.05 0.05 0.03
15:46:25.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:25.83   GAME -- Setting campaign state to 'dlc1'
15:46:25.83   GAME -- Closing state 'dlc1'
15:46:25.83   GAME -- Setting campaign state to 'dlc2'
15:46:25.83   GAME -- Closing state 'dlc2'
15:46:25.84   GAME -- Setting campaign state to 'dlc3'
15:46:25.84   GAME -- Closing state 'dlc3'
15:46:25.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:27.07   Activating screen: GameHistory
15:46:27.07   Activating screen: RelicOnlineTabs
15:46:27.07   Activating screen: ScreenTipPopup
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.50   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:29.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:32.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
15:46:38.46   MOD - Setting player (0) race to: allies_soviets
15:46:38.46   MOD - Setting player (0) race to: 2
15:46:38.46   MOD - Setting player (1) race to: allies_soviets
15:46:38.46   MOD - Setting player (1) race to: 2
15:46:38.46   MOD - Setting player (2) race to: allies_soviets
15:46:38.46   MOD - Setting player (2) race to: 2
15:46:38.46   MOD - Setting player (3) race to: allies_soviets
15:46:38.46   MOD - Setting player (3) race to: 2
15:46:38.46   MOD - Setting player (4) race to: axis
15:46:38.46   MOD - Setting player (4) race to: 3
15:46:38.46   MOD - Setting player (5) race to: axis_panzer_elite
15:46:38.46   MOD - Setting player (5) race to: 4
15:46:38.46   MOD - Setting player (6) race to: axis
15:46:38.46   MOD - Setting player (6) race to: 3
15:46:38.46   MOD - Setting player (7) race to: axis_panzer_elite
15:46:38.46   MOD - Setting player (7) race to: 4
15:46:38.46   Activating screen: MessageBoxPopup
15:46:38.47   Session is marking the local peer PeerID 1 for death
15:46:38.47   Session::GetEvent DestroyPeer success Peer 1 removed
15:46:38.47   Session::DestroyPeer - deallocating peer 1
15:46:38.47   Session - Requested Disconnect for connectionID 2
15:46:38.47   Deallocated route ID=3 for PeerID 1 at WINaddr:5.162.102.231:6112;
15:46:38.47   Session - Requested Disconnect for connectionID 3
15:46:38.47   Closed route 3<>3 for PeerID 1 at WINaddr:5.162.102.231:6112;
15:46:38.47   AdvertisementInternal::Process - EVENT_DESTROYPEER
15:46:38.47   Destroyed Matchinfo
15:46:38.47   APP -- Game Start
15:46:38.47   Sent message game CompanyOfHeroes allowtraffic
15:46:38.47   GAME -- Setting campaign state to 'dlc1'
15:46:38.47   GAME -- Closing state 'dlc1'
15:46:38.48   GAME -- Setting campaign state to 'dlc2'
15:46:38.48   GAME -- Closing state 'dlc2'
15:46:38.48   GAME -- Setting campaign state to 'dlc3'
15:46:38.48   GAME -- Closing state 'dlc3'
15:46:38.49   MessageInternal::DestroyChannel: Destroyed channel 534d5347
15:46:38.50   GAME -- Ending FE
15:46:38.50   UIFrontEnd - Unloading Front End
15:46:38.52   SOUND -- Shutting down ...
15:46:38.57   SOUND -- Shutdown completed!
15:46:38.59   GAME -- Beginning playback P
15:46:38.66   Activating screen: GameLoadScreen
15:46:38.67   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
15:46:38.67   PeerRemoveAll - flushing local session peer data
15:46:38.67   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
15:46:38.67   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
15:46:38.94   THREAD: Hyper-Threading Technology Processors are not detected.
15:46:39.01   SOUND -- Initializing ...
15:46:39.75   SOUND -- Initialization completed!
15:46:39.77   PHYSICS: detected processor(s) capable of handling 4 threads.
15:46:39.77   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\8p_steel_pact\8p_steel pact'
15:46:39.78   MOD -- Using Mod 'Eastern_Front'
15:46:39.80   Unable to load/parse precache file [DATA:scenarios\mp\classic\8p_steel_pact\8p_steel pact_precache.lua]
15:46:39.95   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
15:46:39.95   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
15:46:43.38   GameObjLoader - upgrading load_count from 0 to 1025
15:46:45.72   GameObjLoader - upgrading load_count from 1025 to 1690
15:46:50.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.6, max unsent 0, version err 0, merge 0
15:46:52.62   GameObjLoader - upgrading load_count from 1322 to 4040
15:46:57.36   GameObjLoader 0c328130 - resetting counters
15:46:57.36   GameObjLoader 0c328130 - LOAD_DONE
15:46:57.36   GAME - SessionSetup
15:46:57.54   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
15:46:57.54   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
15:46:57.54   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
15:47:01.01   GAME - CreateGEWorld in 3654 ms
15:47:01.01   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
15:47:01.33   ATMOSPHERE -- Could not load 'data:art/scenarios/presets/atmosphere/8P_STEEL PACT_dawn.APS': 'Unable to open file!'.
15:47:01.33   GAME - SessionSetup finished in 3976 ms
15:47:01.34   GAME - WaterReflectionManagerSetup
15:47:01.34   GAME - WaterReflectionManagerSetup finished in 0 ms
15:47:01.68   MessageInternal::CreateChannel: Created channel 474d4343
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x47646cf8
[Cope FactionFix] - Hook already installed
15:47:01.85   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilate.scar] succeeded.
15:47:03.28   MessageInternal::CreateChannel: Created channel 4d4f444d
15:47:04.00   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
15:47:04.00   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
15:47:04.00   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
15:47:04.00   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
15:47:04.00   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
15:47:04.56   SPEECHMANAGER -- Loaded in 0.509923 seconds
15:47:05.46   GameObjLoader - upgrading load_count from 0 to 356
15:47:06.40   GameObjLoader 0c328290 - resetting counters
15:47:06.40   GameObjLoader 0c328290 - LOAD_DONE
15:47:08.53   PreloadResources took 2064ms.
15:47:08.56   GAME -- Loading completed (30 seconds)
15:47:08.57   SIM -- Setting SyncErrorChecking level to Low
15:47:16.75   Activating screen: GameScreen
15:47:16.75   Activating screen: Decorators
15:47:16.75   Activating screen: Taskbar
15:47:16.75   Activating screen: SubtitleScreen
15:47:16.75   Activating screen: TextOverlayScreen
15:47:16.83   PerformanceRecorder::StartRecording for game size 8
15:47:16.83   GAME -- Starting mission...
15:47:16.87   Warning: binding playback_child7() -- Binding playback_child7: failed bind to widget 'pb_camera_follow'
15:47:23.30   Warning: non auto-match upgrade not found in AE, tuning
15:47:51.01   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:48:52.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:49:12.87   SPEECHMANAGER -- Loaded in 0.513250 seconds
15:49:13.27   Activating screen: Decorators
15:49:13.27   Activating screen: Taskbar
15:49:53.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:50:04.58   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:50:06.57   GAME -- SimulationController::Pause 0
15:50:10.91   SPEECHMANAGER -- Loaded in 0.510573 seconds
15:50:11.33   Activating screen: Decorators
15:50:11.33   Activating screen: Taskbar
15:50:12.61   GAME -- SimulationController::Pause 1
15:50:13.65   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:50:16.92   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:50:20.47   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:50:53.72   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:50:54.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:50:55.63   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:51:19.80   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
15:51:19.80   Muting spew of bind updater.  Fix art and re-test.
15:51:45.34   FXShader::SetVariable( "tex0", T ) uses unknown variable.
15:51:55.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.6, max unsent 0, version err 0, merge 0
15:52:22.50   SPEECHMANAGER -- Loaded in 0.510912 seconds
15:52:22.92   Activating screen: Decorators
15:52:22.92   Activating screen: Taskbar
15:52:56.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:53:57.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:54:16.46   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
15:54:58.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:55:59.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:57:00.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:57:47.30   GAME -- SimulationController::Pause 0
15:57:49.38   GAME -- SimulationController::Pause 1
15:58:01.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
15:59:02.00   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:00:03.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:01:04.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:02:05.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:02:08.43   Warning: upgrade in upgrade prerequisite doesn't exist
16:02:08.53   Warning: upgrade in upgrade prerequisite doesn't exist
16:03:01.78   Was already stealing a skeleton when told to steal another [mortar_target].
16:03:06.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:04:07.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:04:24.47   An ability failed to cast because target validation failed: abilities\axis_terror_bombardment
16:04:35.25   Warning: upgrade in upgrade prerequisite doesn't exist
16:04:35.30   Warning: upgrade in upgrade prerequisite doesn't exist
16:04:35.45    *** Entity command 25 sent from 1006 (JBCONQUERER) but received by entity "ebps\environment\art_ambient\buildings\normandy_industrial\buildings\bricktile01_2x1_01\bricktile01_2x1_01" belonging to (none)
16:04:35.58    *** Entity command 25 sent from 1006 (JBCONQUERER) but received by entity "ebps\environment\art_ambient\buildings\normandy_industrial\buildings\bricktile01_2x1_01\bricktile01_2x1_01" belonging to (none)
16:04:35.85    *** Entity command 25 sent from 1006 (JBCONQUERER) but received by entity "ebps\environment\art_ambient\buildings\normandy_industrial\buildings\bricktile01_2x1_01\bricktile01_2x1_01" belonging to (none)
16:05:08.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:05:21.44   GAME -- SimulationController::Pause 0
16:06:00.57   GAME -- SimulationController::Pause 1
16:06:09.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:07:10.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:07:23.20   GAME -- SimulationController::Pause 0
16:07:27.59   GAME -- SimulationController::Pause 1
16:08:11.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:08:44.36   GAME -- SimulationController::Pause 0
16:08:57.89   GAME -- SimulationController::Pause 1
16:08:58.83   GAME -- SimulationController::Pause 0
16:09:12.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:09:16.59   GAME -- SimulationController::Pause 1
16:09:16.74   GAME -- SimulationController::Pause 0
16:09:17.57   GAME -- SimulationController::Pause 1
16:09:17.96   GAME -- SimulationController::Pause 0
16:09:20.69   GAME -- SimulationController::Pause 1
16:09:27.17   GAME -- SimulationController::Pause 0
16:09:35.38   GAME -- SimulationController::Pause 1
16:09:39.74   GAME -- SimulationController::Pause 0
16:09:44.55   GAME -- SimulationController::Pause 1
16:09:48.45   GAME -- SimulationController::Pause 0
16:09:57.65   GAME -- SimulationController::Pause 1
16:10:13.00   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:10:26.78   GAME -- SimulationController::Pause 0
16:10:56.43   GAME -- SimulationController::Pause 1
16:10:57.50   GAME -- SimulationController::Pause 0
16:11:04.48   GAME -- SimulationController::Pause 1
16:11:05.72   GAME -- SimulationController::Pause 0
16:11:14.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
16:11:14.09   GAME -- SimulationController::Pause 1
16:11:14.54   GAME -- SimulationController::Pause 0
16:11:15.81   GAME -- SimulationController::Pause 1
16:11:15.87   GAME -- SimulationController::Pause 0
16:11:16.53   GAME -- SimulationController::Pause 1
16:11:17.09   GAME -- SimulationController::Pause 0
16:11:24.15   GAME -- SimulationController::Pause 1
16:11:24.52   GAME -- SimulationController::Pause 0
16:11:25.67   GAME -- SimulationController::Pause 1
16:11:27.32   GAME -- SimulationController::Pause 0
16:12:03.38   Activating screen: pause_menu
16:12:03.40   GAME -- SimulationController::Pause 1
16:12:04.42   Activating screen: prompt_generic
16:12:05.13   GameApp::Quit( exitValue = 0 )
16:12:05.16   GAME -- SimulationController::Pause 1
16:12:05.16   Activating screen: AppRestartingForm
16:12:05.16   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
16:12:05.16   Widget attempting to remove a non-existance listener. Widget:bkg_COH
16:12:05.16   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
16:12:05.16   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
16:12:05.16   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
16:12:05.16   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
16:12:05.18   GameApp::ShutFrontEnd
16:12:05.18   GameObj::ShutdownGameObj
16:12:05.18   GAME -- Ending mission (Game In Progress)
16:12:05.36   GameObjLoader 0c328290 - Unload
16:12:05.36   GameObjLoader 0c328290 - resetting counters
16:12:05.40   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
16:12:05.40   MOD -- Game Over at frame 13716
16:12:05.92   GameObjLoader 0c328130 - Unload
16:12:05.92   GameObjLoader 0c328130 - resetting counters
16:12:06.04   SOUND -- Shutting down ...
16:12:06.19   SOUND -- Shutdown completed!
16:12:06.19   MessageInternal::DestroyChannel: Destroyed channel 474d4343
16:12:06.21   ~FEAppListener - Flushing queued online notifications
16:12:06.78   DLLDriverLinker -- 2 DLL drivers found.
16:12:06.78   ActionDatabase : [2344] global actions, [147] dummy actions.
16:12:06.78   NetworkManager::Destroy - destroying network manager
16:12:06.78   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
16:12:06.78   MessageInternal::DestroyChannel: Destroyed channel 50525459
16:12:06.78   PartyInternal: Fade to black
16:12:06.78   AutomatchInternal: Fade to black
16:12:06.78   PeerRemoveAll - flushing local session peer data
16:12:06.78   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
16:12:06.78   MessageInternal::DestroyChannel: Destroyed channel 47535450
16:12:06.78   Session - Requested Disconnect for connectionID 1
16:12:06.78   SessionManager::UnregisterSession - Unregistering session 03cb5b58
16:12:06.78   SessionManager::~SessionManager - uninitializing
16:12:06.79   Net::ThreadFunction - Returning from the network thread function...
16:12:06.79   Transport::~Transport - shutting down transport
16:12:06.79   Transport::~Connection - closing WINaddr:255.255.255.255:6112;,WINaddr:255.255.255.255:6112;
16:12:06.79   Transport::~Connection - closing WINaddr:127.0.0.1:6112;,WINaddr:127.0.0.1:6112;
16:12:06.79   Transport::~Connection - closing WINaddr:5.162.102.231:6112;,WINaddr:5.162.102.231:6112;
16:12:06.79   Wrote 269 bytes to network datastore login_cache.bin
16:12:06.79   Wrote 52 bytes to network datastore global_cache.bin
16:12:06.79   Wrote 100 bytes to network datastore ping_cache.bin
16:12:06.79   Datastore -- uninitialize complete
16:12:06.79   QuazalInitializer - static uninit of Quazal library
16:12:06.79   QuazalInitializer - unhooking memory 0 currently allocated, 685 allocations, total of 66684 bytes
