-- Log file for all dbTracef messages --

22:26:48.95   NetworkManager::Create - creating network manager
22:26:48.95   Read 269 bytes from network datastore login_cache.bin
22:26:48.95   Read 52 bytes from network datastore global_cache.bin
22:26:48.95   Read 28 bytes from network datastore ping_cache.bin
22:26:48.95   QuazalInitializer - static initializing Quazal library
22:26:48.95   PingCache - populating cache with 0 pings
22:26:49.00   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
22:26:49.06   WinTransport - CreateSocket exclusive broadcast socket was available.
22:26:49.06   WinTransport - CreateSocket listening for broadcasts on default port
22:26:49.06   WinTransport - Host Name: NgocQuang-PC, aliases: , type=AF_INET, len=4
22:26:49.06   WinTransport - Host IP Address #0: 5.3.208.164
22:26:49.06   WinTransport - Interface #0: ip:5.3.208.164, broadcast:5.3.208.164, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
22:26:49.06   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
22:26:49.06   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
22:26:49.06   WinTransport - Quazal address string = udp:/address=5.3.208.164;port=6112
22:26:49.06   SessionManager - Peer Header Size = 16 bytes
22:26:49.06   SessionManager - Game Data overhead = 7 bytes
22:26:49.06   SessionManager - Proxy overhead = 7 bytes
22:26:49.06   MessageInternal::CreateChannel: Created channel 47535450
22:26:49.06   Session::Initialize - info, initializing session object, using threads.
22:26:49.06   SessionManager::RegisterSession - Registering new session 079a2e40
22:26:49.06   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
22:26:49.06   AutomatchInternal: Instantiating
22:26:49.06   PartyInternal: Instantiating
22:26:49.06   MessageInternal::CreateChannel: Created channel 50525459
22:26:49.06   MessageInternal::CreateChannel: Created channel 51434b4d
22:26:49.06   Net::ThreadFunction - Entering network thread function...
22:26:49.07   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
22:26:49.07   MessageInternal::CreateChannel: Created channel 534d5347
22:26:49.07   MessageInternal::CreateChannel: Created channel 474d4343
22:26:49.07   MessageInternal::CreateChannel: Created channel 474f424a
22:26:49.07   MessageInternal::CreateChannel: Created channel 4d4f444d
22:26:49.07   MessageInternal::CreateChannel: Created channel 53594e43
22:26:49.07   MessageInternal::DestroyChannel: Destroyed channel 534d5347
22:26:49.07   MessageInternal::DestroyChannel: Destroyed channel 474d4343
22:26:49.07   MessageInternal::DestroyChannel: Destroyed channel 474f424a
22:26:49.07   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
22:26:49.07   MessageInternal::DestroyChannel: Destroyed channel 53594e43
22:26:49.07   GAME -- Available memory: 3071MB Physical RAM, 3069MB Pagefile, 4095 Virtual Address Space
22:26:49.08   Transport - Largest received is now 19
22:26:49.08   Transport::OpenInternal request to WINaddr:127.0.0.1:6112;
22:26:49.09   Transport - Largest sent is now 19
22:26:51.91   DLLDriverLinker -- Adding driver 'spDx10.dll'.
22:26:51.92   DLLDriverLinker -- Adding driver 'spDx9.dll'.
22:26:51.92   DLLDriverLinker -- 2 DLL drivers found.
22:26:51.98   SPDx10 -- Adapter [ASUS EAH3450 Series]: 508MB dedicated video memory, 0MB dedicated system memory and 1279MB shared system memory.
22:26:53.64   DLLDriverLinker -- 2 DLL drivers found.
22:26:53.77   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
22:26:53.77   GAME -- Resolution set to 1024x768 (fullscreen).
22:26:53.80   SPDx9 -- Driver Name = atiumdag.dll  Desc = ASUS EAH3450 Series
22:26:53.80   SPDx9 -- Driver Vendor = 0x1002  Device = 0x95C5  SubSys = 0x01E21043  Rev = 0x0000
22:26:53.80   SPDx9 -- Driver Version  Product = 0x0007  Version = 0x000E  SubVersion = 0x00  Build = 5.97
22:26:53.80   SPDx9 -- Driver GUID = {D7B71EE2-D685-11CF-A671-E821A1C2CA35}
22:26:54.39   SPDx9 -- 1774MB available Texture Memory
22:26:54.39   ShaderDatabase: using shader profile [ps30]
22:26:54.91   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
22:26:55.16   GameObjLoader 091de318 - resetting counters
22:26:55.16   GameObjLoader 091de318 - Created loader
22:26:55.16   GameObjLoader 091de478 - resetting counters
22:26:55.16   GameObjLoader 091de478 - Created loader
22:27:10.58   GAME -- Beginning FE
22:27:10.58   Sent message game CompanyOfHeroes started 3892 602 allowtraffic
22:27:10.58   RemoteDLManager - Connection Restored.
22:27:10.58   UIFrontEnd - Loading Front End
22:27:10.58   THREAD: Hyper-Threading Technology Processors are not detected.
22:27:10.68   SOUND -- Initializing ...
22:27:10.74   INNIMapDCA Key not found: sp_speechducker::time
22:27:11.27   SOUND -- Initialization completed!
22:27:11.27   UIFrontEnd - Initializing Forms
22:27:13.05   CampaignFilter::BindFilterSpecificWidgets()
22:27:13.22   Activating screen: MovieScreen
22:27:13.22   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
22:27:37.98   Activating screen: AppLoadingForm
22:27:37.98   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
22:27:38.65   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
22:27:38.65   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
22:27:38.65   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
22:27:38.66   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
22:27:38.66   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
22:27:38.66   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
22:27:39.03   GAME -- Using player profile NGOCQUANG-PC
22:27:42.11   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
22:27:43.01   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
22:27:44.01   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
22:27:44.01   RendezvousManager: CreateSession - starting profile=Guest login
22:27:44.06   GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure.
22:27:44.06   RendezvousManager - unable to connect to the server as guest
22:27:44.08   OnConnect: connect failure
22:27:44.08   RendezvousManager::Shutdown - deleting instance
22:27:44.08   RendezvousManager - terminating all server calls in progress
22:27:44.08   CallManager - terminating all server calls in progress (1 in progress)
22:27:44.08   RendezvousManager - destroying chat handler
22:27:44.08   ~RendezvousManager - m_state = 7, no need for server disconnect
22:27:44.10   OnConnect: this wasnt a reconnect, no need for autologin
22:27:44.11   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
22:27:44.12   CRC & Version Info : 0000025a:ce5073b5:ec3c9e0d eastern_front:602:factionfix.dll 1
22:27:44.12   Activating screen: FEMovie
22:27:44.12   Activating screen: OnlineWidget
22:27:44.12   Activating screen: RelicOnlineLogin
22:27:44.18   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
22:27:44.18   You have a valid version of the game.
22:27:44.20   AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now
22:27:44.20   installed_products = ( DLC1 DLC2 DLC3 )
22:27:44.20   OnLogin: no previous login, auto selecting profile not required
22:27:44.20   Activating screen: MessageBoxPopup
22:27:44.20   Activating screen: MessageBoxPopup
22:27:44.20   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
22:27:50.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
22:27:54.55   SPDx9 -- 1773MB available Texture Memory
22:28:04.38   SPDx9 -- 1773MB available Texture Memory
22:28:12.30   Beginning media verification...
22:28:12.30   Validator type : validators\udro\udro.dll
22:28:12.30   Activating screen: MessageBoxPopup
22:28:12.33   Activating screen: FE_mm_01
22:28:12.33   GAME -- Setting campaign state to 'dlc1'
22:28:12.33   GAME -- Closing state 'dlc1'
22:28:12.33   GAME -- Setting campaign state to 'dlc2'
22:28:12.33   GAME -- Closing state 'dlc2'
22:28:12.34   GAME -- Setting campaign state to 'dlc3'
22:28:12.34   GAME -- Closing state 'dlc3'
22:28:13.70   Activating screen: MessageBoxPopup
22:28:13.70   Created Matchinfo
22:28:13.70   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
22:28:13.70   starting LAN hosting
22:28:13.73   OnJoinAdvertisementSuccess - joined online match, server leave notification required
22:28:13.73   starting local hosting
22:28:13.73   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
22:28:13.73   Allocated route ID=0 for PeerID 1 at WINaddr:5.3.208.164:6112;
22:28:13.73   Transport::OpenInternal request to WINaddr:5.3.208.164:6112;
22:28:13.73   Session::Host sid = 96C4D0C1480D1ACB, hostURL = , local addresses = WINaddr:5.3.208.164:6112;
22:28:13.73   ValidateCustomData: called with 217 bytes of custom data
22:28:13.73   Host accepted Peer 1 into the match at address list=WINaddr:5.3.208.164:6112;, routes=WINaddr:5.3.208.164:6112;
22:28:13.73   AdvertisementInternal::Process - EVENT_NEWPEER
22:28:13.73   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
22:28:13.75   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
22:28:13.75   hosting - Session is connected
22:28:13.75   Net::Session::SetVisible - session is set to VISIBLE.
22:28:13.75   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:5.3.208.164:6112; (ping=0ms) 100.00%, pending=0, dead=0
22:28:13.75   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
22:28:13.76   hosting completed successfully
22:28:13.76   HostAsync - completed with HostResult = 0
22:28:13.76   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
22:28:13.76   Activating screen: OnlineGameSetup
22:28:13.76   MessageInternal::CreateChannel: Created channel 534d5347
22:28:13.76   Transport - Largest received is now 457
22:28:13.77   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:13.77   MatchInternal::SetMatchType - new type 14 - updating server
22:28:13.78   SetVisible called while !IsConnected
22:28:13.78   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:13.78   Transport - Largest received is now 513
22:28:13.79   Activating screen: RelicOnlineChat
22:28:13.79   Activating screen: RelicOnlineNewsScreen
22:28:13.79   Activating screen: RelicOnlineStatsScreen
22:28:13.79   Activating screen: Achievements
22:28:13.79   GAME -- Setting campaign state to 'dlc1'
22:28:13.79   GAME -- Closing state 'dlc1'
22:28:13.79   GAME -- Setting campaign state to 'dlc1'
22:28:13.79   GAME -- Closing state 'dlc1'
22:28:13.80   GAME -- Setting campaign state to 'dlc2'
22:28:13.80   GAME -- Closing state 'dlc2'
22:28:13.80   GAME -- Setting campaign state to 'dlc3'
22:28:13.80   GAME -- Closing state 'dlc3'
22:28:13.80   GAME -- Setting campaign state to 'dlc1'
22:28:13.80   GAME -- Closing state 'dlc1'
22:28:13.80   Transport - Largest received is now 1171
22:28:13.81   Activating screen: GameHistory
22:28:13.81   GAME -- Setting campaign state to 'dlc1'
22:28:13.81   GAME -- Closing state 'dlc1'
22:28:13.81   GAME -- Setting campaign state to 'dlc2'
22:28:13.81   GAME -- Closing state 'dlc2'
22:28:13.81   GAME -- Setting campaign state to 'dlc3'
22:28:13.81   GAME -- Closing state 'dlc3'
22:28:13.82   Activating screen: OnlineGameSetup
22:28:13.82   Activating screen: RelicOnlineTabs
22:28:13.82   AutomatchInternal::OnHostComplete - Completed Host with success=1
22:28:13.82   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
22:28:13.82   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
22:28:13.83   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:13.83   Net::Session::SetVisible - session is set to VISIBLE.
22:28:13.83   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:13.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:13.90   GAME -- Setting campaign state to 'dlc1'
22:28:13.90   GAME -- Closing state 'dlc1'
22:28:13.90   GAME -- Setting campaign state to 'dlc2'
22:28:13.90   GAME -- Closing state 'dlc2'
22:28:13.90   GAME -- Setting campaign state to 'dlc3'
22:28:13.90   GAME -- Closing state 'dlc3'
22:28:13.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:15.24   Activating screen: RaceSelectionPopup
22:28:15.92   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:15.92   Net::Session::SetVisible - session is set to VISIBLE.
22:28:15.92   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:15.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:15.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:16.52   Activating screen: DynamicPopupMenu
22:28:17.15   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:17.15   Net::Session::SetVisible - session is set to VISIBLE.
22:28:17.15   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:17.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:17.20   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:17.71   Activating screen: DynamicPopupMenu
22:28:18.41   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:18.41   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:18.41   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:18.42   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:18.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:19.11   Activating screen: RaceSelectionPopup
22:28:19.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:19.80   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:19.80   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:19.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:19.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:20.23   Activating screen: RaceSelectionPopup
22:28:20.92   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:20.92   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:20.92   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:20.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:20.97   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:21.67   Activating screen: DynamicPopupMenu
22:28:22.55   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:22.55   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:22.55   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:22.56   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:22.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:23.28   Activating screen: DynamicPopupMenu
22:28:24.04   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:24.04   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:24.04   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:24.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:24.10   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:24.78   Activating screen: DynamicPopupMenu
22:28:25.61   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:25.61   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:25.61   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:25.62   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:25.67   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:26.34   Activating screen: DynamicPopupMenu
22:28:27.34   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:27.34   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:27.34   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:27.35   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:27.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:28.05   Activating screen: Achievements
22:28:28.05   Activating screen: RelicOnlineTabs
22:28:32.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:32.80   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:32.80   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:32.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:32.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:33.95   Activating screen: OnlineGameSetup
22:28:33.95   Activating screen: RelicOnlineTabs
22:28:36.07   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:36.07   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:36.07   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:36.09   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:36.14   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:37.64   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:37.64   Net::Session::SetVisible - session is set to INVISIBLE.
22:28:37.64   GetMaxFrameTimeFromProfile: players=6 expected FPS=19.252983, bars=5, max avg=0.042, sd=0.010, 2 samples =  0.05 0.03
22:28:37.64   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:37.69   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:38.55   GameSetupForm - No win/loss reporting and only a single player, disconnecting
22:28:38.55   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
22:28:38.55   SetVisible called while !IsConnected
22:28:38.55   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:38.56   GameSetupForm - Starting game
22:28:38.56   GameInfo::ResetInfo - SyncLevel set to 0 on reset
22:28:38.56   PopulateGameInfo - random seed:[1308670118], guid:[{7d776342-63e9-4616-aec7-57ef899b79a7}], sync level:[2]
22:28:38.56   Error loading [DATA:levelingCurve.lua]
22:28:38.56   Error loading [DATA:levelingCurve.lua]
22:28:38.57   Error loading [DATA:levelingCurve.lua]
22:28:38.57   Error loading [DATA:levelingCurve.lua]
22:28:38.57   Error loading [DATA:levelingCurve.lua]
22:28:38.57   Error loading [DATA:levelingCurve.lua]
22:28:38.57   MOD - Setting player (0) race to: allies_soviets
22:28:38.57   MOD - Setting player (0) race to: 2
22:28:38.57   MOD - Setting player (1) race to: allies_soviets
22:28:38.57   MOD - Setting player (1) race to: 2
22:28:38.57   MOD - Setting player (2) race to: allies_soviets
22:28:38.57   MOD - Setting player (2) race to: 2
22:28:38.57   MOD - Setting player (3) race to: axis
22:28:38.57   MOD - Setting player (3) race to: 3
22:28:38.57   MOD - Setting player (4) race to: axis
22:28:38.57   MOD - Setting player (4) race to: 3
22:28:38.57   MOD - Setting player (5) race to: axis
22:28:38.57   MOD - Setting player (5) race to: 3
22:28:38.57   Activating screen: MessageBoxPopup
22:28:38.57   Session is marking the local peer PeerID 1 for death
22:28:38.57   Session::GetEvent DestroyPeer success Peer 1 removed
22:28:38.57   Session::DestroyPeer - deallocating peer 1
22:28:38.57   Session - Requested Disconnect for connectionID 2
22:28:38.57   Deallocated route ID=3 for PeerID 1 at WINaddr:5.3.208.164:6112;
22:28:38.57   Session - Requested Disconnect for connectionID 3
22:28:38.57   Closed route 3<>3 for PeerID 1 at WINaddr:5.3.208.164:6112;
22:28:38.57   AdvertisementInternal::Process - EVENT_DESTROYPEER
22:28:38.57   Destroyed Matchinfo
22:28:38.58   APP -- Game Start
22:28:38.58   Sent message game CompanyOfHeroes allowtraffic
22:28:38.58   GAME -- Setting campaign state to 'dlc1'
22:28:38.58   GAME -- Closing state 'dlc1'
22:28:38.59   GAME -- Setting campaign state to 'dlc2'
22:28:38.59   GAME -- Closing state 'dlc2'
22:28:38.59   GAME -- Setting campaign state to 'dlc3'
22:28:38.59   GAME -- Closing state 'dlc3'
22:28:38.59   MessageInternal::DestroyChannel: Destroyed channel 534d5347
22:28:38.60   GAME -- Ending FE
22:28:38.60   UIFrontEnd - Unloading Front End
22:28:38.62   SOUND -- Shutting down ...
22:28:38.67   SOUND -- Shutdown completed!
22:28:38.69
22:28:38.69   GAME -- *** Beginning mission 6P_CHINON (1 Humans, 5 Computers) ***
22:28:38.69
22:28:38.69   GAME -- Recording game
22:28:38.78   Activating screen: GameLoadScreen
22:28:38.78   ReportGameStartStats - no connection to server unable to report
22:28:38.78   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
22:28:38.78   PeerRemoveAll - flushing local session peer data
22:28:38.78   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
22:28:38.78   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
22:28:39.19   THREAD: Hyper-Threading Technology Processors are not detected.
22:28:39.27   SOUND -- Initializing ...
22:28:40.01   SOUND -- Initialization completed!
22:28:40.05   PHYSICS: detected processor(s) capable of handling 4 threads.
22:28:40.07   MOD -- Locating MOD for scenario 'DATA:Scenarios\MP\6P_CHINON'
22:28:40.07   MOD -- Using Mod 'Eastern_Front'
22:28:40.08   Unable to load/parse precache file [DATA:Scenarios\MP\6P_CHINON_precache.lua]
22:28:40.18   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'.  Expensive operation
22:28:40.19   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'.  Expensive operation
22:28:43.68   GameObjLoader - upgrading load_count from 0 to 1226
22:28:47.71   GameObjLoader - upgrading load_count from 1226 to 2037
22:28:47.72   PHYSICS -- Created node factory 'HVOK'
22:28:47.72   PHYSICS -- Created node factory 'DMMY'
22:28:51.00   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 1.6/0.1, #p/sec[s/r] = 0.3/0.6, max unsent 0, version err 0, merge 0
22:29:12.50   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'.  Expensive operation
22:29:26.47   SPDx9 -- 1773MB available Texture Memory
22:29:30.94   SPDx9 -- 1773MB available Texture Memory
22:29:33.59   GameObjLoader - upgrading load_count from 38 to 4874
22:29:45.59   GameObjLoader 091de318 - resetting counters
22:29:45.59   GameObjLoader 091de318 - LOAD_DONE
22:29:45.59   GAME - SessionSetup
22:29:45.59   GAME -- Can't initialize scenario pack more than once.
22:29:46.17   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
22:29:46.18   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
22:29:46.18   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
22:29:47.87   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'.  Expensive operation
22:29:48.19   GAME - CreateGEWorld in 2594 ms
22:29:48.22   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
22:29:48.52   GAME - SessionSetup finished in 2929 ms
22:29:48.53   GAME - WaterReflectionManagerSetup
22:29:48.53   GAME - WaterReflectionManagerSetup finished in 0 ms
22:29:49.78   MessageInternal::CreateChannel: Created channel 474d4343
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x065fd820
[Cope FactionFix] - patched @0x69ef5491
[Cope FactionFix] - hooked @0x69ef5492
[Cope FactionFix] - patched @0x69ef4a07
[Cope FactionFix] - hooked @0x69ef4a07
[Cope FactionFix] - Hook installed
22:29:50.08   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilate.scar] succeeded.
22:29:50.36   MOD -- Player  (unused player) (frame 0) (KillPlayer)
22:29:50.36   MOD -- Player  (unused player) (frame 0) (KillPlayer)
22:29:50.99   MessageInternal::CreateChannel: Created channel 4d4f444d
22:29:51.87   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
22:29:51.88   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
22:29:51.88   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
22:29:51.88   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
22:29:51.88   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
22:29:52.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.6/0.0, #p/sec[s/r] = 0.3/0.4, max unsent 0, version err 0, merge 0
22:29:52.51   SPEECHMANAGER -- Loaded in 0.572961 seconds
22:29:54.54   GameObjLoader - upgrading load_count from 0 to 418
22:29:56.71   GameObjLoader 091de478 - resetting counters
22:29:56.71   GameObjLoader 091de478 - LOAD_DONE
22:29:59.04   PreloadResources took 2246ms.
22:29:59.20   GAME -- Loading completed (80 seconds)
22:29:59.20   SIM -- Setting SyncErrorChecking level to None
22:30:10.41   Activating screen: GameScreen
22:30:10.41   Activating screen: Decorators
22:30:10.41   Activating screen: Taskbar
22:30:10.41   Activating screen: SubtitleScreen
22:30:10.41   Activating screen: TextOverlayScreen
22:30:10.54   PerformanceRecorder::StartRecording for game size 6
22:30:10.54   GAME -- Starting mission...
22:30:12.08   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
22:30:12.08   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
22:30:12.08   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
22:30:12.08   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
22:30:12.08   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
22:30:16.72   Warning: non auto-match upgrade not found in AE, tuning
22:30:41.55   Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
22:30:47.77   Activating screen: Command_Tree
22:30:49.34   Activating screen: Command_Branch
22:30:53.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
22:31:01.78   Activating screen: pause_menu
22:31:01.78   GAME -- SimulationController::Pause 0
22:31:05.52   SPDx9 -- 1756MB available Texture Memory
22:31:07.81   GAME -- SimulationController::Pause 1
22:31:11.82   Activating screen: pause_menu
22:31:11.82   GAME -- SimulationController::Pause 0
22:31:18.71   SPDx9 -- 1756MB available Texture Memory
22:31:23.89   GAME -- SimulationController::Pause 1
22:31:46.05   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
22:31:54.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
22:32:08.74   Warning: binding repeat_1(Ability: abilities\smg_pps) -- ui index '0' out of bounds; range is [1, 12]
22:32: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
22:33:01.77   Activating screen: pause_menu
22:33:01.77   GAME -- SimulationController::Pause 0
22:33:06.01   SPDx9 -- 1756MB available Texture Memory
22:33:17.52   Got dlman msg [dlmanager shutdown user]
22:33:34.00   SPDx9 -- 1756MB available Texture Memory
22:33:37.60   GAME -- SimulationController::Pause 1
22:33:56.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
22:34: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
22:35:16.84   Activating screen: pause_menu
22:35:16.84   GAME -- SimulationController::Pause 0
22:35:20.73   SPDx9 -- 1756MB available Texture Memory
22:35:23.55   GAME -- SimulationController::Pause 1
22:35: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
22:36:23.29   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:23.29   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:23.29   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
22:36:26.52   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:26.52   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:26.52   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
22:36:26.90   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:26.90   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:26.90   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
22:36:27.40   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
22:36:27.40   Muting spew of bind updater.  Fix art and re-test.
22:36:33.17   Activating screen: pause_menu
22:36:33.18   GAME -- SimulationController::Pause 0
22:36:34.69   Activating screen: prompt_generic
22:36:35.49   MOD -- Player NGOCQUANG-PC (network abort) (frame 2554) (KillPlayer)
22:36:35.49   GameApp::ReportStats
22:36:35.49   ReportStats - untracked game, no need to report match 4294967295:4294967295
22:36:35.49   ReportSimStats - nothing to report, no connection to the server
22:36:35.49   ReportStats - ReportSimStats failed
22:36:35.49   GameApp::Quit( exitValue = 0 )
22:36:35.52   GAME -- SimulationController::Pause 1
22:36:35.52   Activating screen: AppRestartingForm
22:36:35.52   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
22:36:35.52   Widget attempting to remove a non-existance listener. Widget:bkg_COH
22:36:35.52   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
22:36:35.52   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
22:36:35.52   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
22:36:35.52   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
22:36:35.54   GameApp::ShutFrontEnd
22:36:35.54   GameObj::ShutdownGameObj
22:36:35.54   GAME -- Ending mission (Game over)
22:36:35.61   GameObjLoader 091de478 - Unload
22:36:35.61   GameObjLoader 091de478 - resetting counters
22:36:35.79   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
22:36:35.79   MOD -- Game Over at frame 2554
22:36:35.98   GameObjLoader 091de318 - Unload
22:36:35.98   GameObjLoader 091de318 - resetting counters
22:36:36.11   SOUND -- Shutting down ...
22:36:36.19   SOUND -- Shutdown completed!
22:36:36.20   MessageInternal::DestroyChannel: Destroyed channel 474d4343
22:36:36.21   ~FEAppListener - Flushing queued online notifications
22:36:36.81   DLLDriverLinker -- 2 DLL drivers found.
22:36:36.82   ActionDatabase : [2344] global actions, [33] dummy actions.
22:36:36.82   NetworkManager::Destroy - destroying network manager
22:36:36.82   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
22:36:36.82   MessageInternal::DestroyChannel: Destroyed channel 50525459
22:36:36.82   PartyInternal: Fade to black
22:36:36.82   AutomatchInternal: Fade to black
22:36:36.82   PeerRemoveAll - flushing local session peer data
22:36:36.82   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
22:36:36.82   MessageInternal::DestroyChannel: Destroyed channel 47535450
22:36:36.82   Session - Requested Disconnect for connectionID 1
22:36:36.82   SessionManager::UnregisterSession - Unregistering session 079a2e40
22:36:36.82   SessionManager::~SessionManager - uninitializing
22:36:36.83   Net::ThreadFunction - Returning from the network thread function...
22:36:36.83   Transport::~Transport - shutting down transport
22:36:36.83   Transport::~Connection - closing WINaddr:255.255.255.255:6112;,WINaddr:255.255.255.255:6112;
22:36:36.83   Transport::~Connection - closing WINaddr:127.0.0.1:6112;,WINaddr:127.0.0.1:6112;
22:36:36.83   Transport::~Connection - closing WINaddr:5.3.208.164:6112;,WINaddr:5.3.208.164:6112;
22:36:36.83   Wrote 269 bytes to network datastore login_cache.bin
22:36:36.83   Wrote 52 bytes to network datastore global_cache.bin
22:36:36.83   Wrote 28 bytes to network datastore ping_cache.bin
22:36:36.83   Datastore -- uninitialize complete
22:36:36.83   QuazalInitializer - static uninit of Quazal library
22:36:36.83   QuazalInitializer - unhooking memory 0 currently allocated, 203 allocations, total of 19904 bytes
