-- Log file for all dbTracef messages --

14:46:17.04   NetworkManager::Create - creating network manager
14:46:17.04   Read 688 bytes from network datastore login_cache.bin
14:46:17.04   Read 120560 bytes from network datastore global_cache.bin
14:46:17.04   Read 857458 bytes from network datastore ping_cache.bin
14:46:17.06   QuazalInitializer - static initializing Quazal library
14:46:17.09   PingCache - populating cache with 36545 pings
14:46:17.09   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
14:46:17.10   WinTransport - CreateSocket exclusive broadcast socket was available.
14:46:17.10   WinTransport - CreateSocket listening for broadcasts on default port
14:46:17.10   WinTransport - Host Name: jan-4a3095fc21a, aliases: , type=AF_INET, len=4
14:46:17.10   WinTransport - Host IP Address #0: [IP address redacted]
14:46:17.10   WinTransport - Interface #0: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
14:46:17.10   WinTransport - Interface #1: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_LOOPBACK IFF_MULTICAST
14:46:17.10   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:46:17.10   WinTransport - Quazal address string = udp:/address=[IP address redacted];port=6112
14:46:17.10   SessionManager - Peer Header Size = 16 bytes
14:46:17.10   SessionManager - Game Data overhead = 7 bytes
14:46:17.10   SessionManager - Proxy overhead = 7 bytes
14:46:17.10   MessageInternal::CreateChannel: Created channel 47535450
14:46:17.10   Session::Initialize - info, initializing session object, using threads.
14:46:17.10   SessionManager::RegisterSession - Registering new session 0ec3d0a0
14:46:17.10   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:46:17.10   AutomatchInternal: Instantiating
14:46:17.10   PartyInternal: Instantiating
14:46:17.10   Net::ThreadFunction - Entering network thread function...
14:46:17.10   MessageInternal::CreateChannel: Created channel 50525459
14:46:17.10   MessageInternal::CreateChannel: Created channel 51434b4d
14:46:17.12   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:46:17.12   MessageInternal::CreateChannel: Created channel 534d5347
14:46:17.12   MessageInternal::CreateChannel: Created channel 474d4343
14:46:17.12   MessageInternal::CreateChannel: Created channel 474f424a
14:46:17.12   MessageInternal::CreateChannel: Created channel 4d4f444d
14:46:17.12   MessageInternal::CreateChannel: Created channel 53594e43
14:46:17.12   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:46:17.12   MessageInternal::DestroyChannel: Destroyed channel 474d4343
14:46:17.12   MessageInternal::DestroyChannel: Destroyed channel 474f424a
14:46:17.12   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
14:46:17.12   MessageInternal::DestroyChannel: Destroyed channel 53594e43
14:46:17.12   GAME -- Available memory: 2039MB Physical RAM, 1890MB Pagefile, 2047 Virtual Address Space
14:46:17.14   Transport - Largest received is now 19
14:46:17.14   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:46:22.28   DLLDriverLinker -- Adding driver 'spDx9.dll'.
14:46:22.28   DLLDriverLinker -- 1 DLL drivers found.
14:46:22.54   DLLDriverLinker -- 1 DLL drivers found.
14:46:22.73   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
14:46:22.73   GAME -- Resolution set to 1024x768 (fullscreen).
14:46:22.75   SPDx9 -- Driver Name = igxprd32.dll  Desc = Intel(R) 82915G/GV/910GL Express Chipset Family
14:46:22.75   SPDx9 -- Driver Vendor = 0x8086  Device = 0x2582  SubSys = 0x300C103C  Rev = 0x0004
14:46:22.75   SPDx9 -- Driver Version  Product = 0x0006  Version = 0x000E  SubVersion = 0x00  Build = 47.64
14:46:22.75   SPDx9 -- Driver GUID = {D7B78E66-66C2-11CF-1061-0610A5C2CB35}
14:46:23.29   SPDx9 -- 93MB available Texture Memory
14:46:23.29   SPDx9 -- Unknown video driver, assuming [1] GPUs.
14:46:23.31   ShaderDatabase: using shader profile [ps15]
14:46:24.23   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
14:46:24.50   GameObjLoader 07c40fe8 - resetting counters
14:46:24.50   GameObjLoader 07c40fe8 - Created loader
14:46:24.50   GameObjLoader 07db6d80 - resetting counters
14:46:24.50   GameObjLoader 07db6d80 - Created loader
14:46:24.82   GAME -- Beginning FE
14:46:24.82   Sent message game CompanyOfHeroes started 3824 602 allowtraffic
14:46:24.82   RemoteDLManager - Connection Restored.
14:46:24.82   UIFrontEnd - Loading Front End
14:46:24.93   SOUND -- Initializing ...
14:46:25.03   INNIMapDCA Key not found: sp_speechducker::time
14:46:25.82   SOUND -- Initialization completed!
14:46:25.82   UIFrontEnd - Initializing Forms
14:46:28.53   CampaignFilter::BindFilterSpecificWidgets()
14:46:28.73   Activating screen: MovieScreen
14:46:28.73   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio -1]
14:46:31.37   Activating screen: AppLoadingForm
14:46:31.37   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:46:32.39   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
14:46:32.39   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
14:46:32.39   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
14:46:32.39   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
14:46:32.39   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
14:46:32.39   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
14:46:32.96   GAME -- Using player profile JAN-4A3095FC21A
14:46:34.54   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
14:46:35.59   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
14:46:37.31   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
14:46:37.31   RendezvousManager: CreateSession - starting profile=Guest login
14:46:38.68   RendezvousManager: Login complete and successfull
14:46:38.68   RendezvousManager initialized
14:46:38.81   Current server English:live version is 602.0, client is 602.0
14:46:38.81   OnConnect: successful connection established, enabling reconnect
14:46:38.81   OnConnect: this wasnt a reconnect, no need for autologin
14:46:38.81   Logging in jwvd1 on controller:0
14:46:39.62   Requesting Relic Downloader soft throttle call [LoginAsync] has time 813
14:46:39.62   Sent message game CompanyOfHeroes softthrottle
14:46:39.62   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:46:40.25   Login completed: ACCOUNT_VALIDATED
14:46:40.25   Found 2 profiles for account jwvd1
14:46:40.25   Found profile: jwvd1
14:46:40.25   Found profile: jcvd1
14:46:40.25   installed_products = ( CXP1 DLC1 DLC2 DLC3 )
14:46:40.25   OnLogin: no previous login, auto selecting profile not required
14:46:40.26   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:46:40.26   CRC & Version Info : 0000025a:5f21ef68:ec3c9e0d eastern_front:602:factionfix.dll 1
14:46:40.29   Activating screen: FEMovie
14:46:40.29   Activating screen: OnlineWidget
14:46:40.29   Activating screen: RelicOnlineProfileSelect
14:46:40.31   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:46:40.31   Activating screen: RelicOnlineWait
14:46:40.31   RendezvousManager - destroying chat handler
14:46:40.31   RendezvousManager: CreateSession - starting logout profile = 100:Guest
14:46:42.73   RendezvousManager: Logout complete
14:46:42.73   RendezvousManager - terminating all server calls in progress
14:46:42.73   CallManager - terminating all server calls in progress (1 in progress)
14:46:42.73   RendezvousManager: OnCredentialsEvent - starting profile login
14:46:43.79   RendezvousManager: Login complete and successfull
14:46:43.79   RendezvousManager - creating chat handler
14:46:43.81   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
14:46:44.04   QuazalSelectProfileAsync - Got UserID
14:46:44.09   Transport - Largest sent is now 16
14:46:44.20   Transport - Largest received is now 42
14:46:44.23   GetUserStats requested stats for PIDs ( 3263704 ) (best:0, full:1)
14:46:44.37   SelectProfileAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=62782;PID=3263704;RVCID=80805398], private [udp:/address=[IP address redacted];port=6112;PID=3263704]
14:46:44.50   QuazalSelectProfileAsync - Got Full Stats
14:46:44.68   GetAutomatchMaps: Got [32] maps
14:46:44.71   PopulateArmyListBox - skipping race 2
14:46:44.71   PopulateArmyListBox - skipping race 0
14:46:44.71   PopulateArmyListBox - skipping race 1
14:46:44.71   PopulateArmyListBox - skipping race 3
14:46:44.71   AutoMatchForm::OnArmySelectionChanged - sending request info
14:46:44.71   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.391359, bars=1, max avg=0.201, sd=0.217, 5 samples =  0.63 0.04 0.12 0.15 0.06
14:46:44.71   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
14:46:44.73   QuazalSelectProfileAsync - Got Automatch maps
14:46:48.06   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
14:46:48.23   Profile [00000000:0031ccd8] selected on controller#0
14:46:48.23   Activating screen: FEMovie
14:46:48.23   Activating screen: OnlineWidget
14:46:48.23   Activating screen: FE_mm_01
14:46:48.23   Activating screen: RelicOnlineWait
14:46:48.26   GAME -- Setting campaign state to 'cxp2'
14:46:48.26   GAME -- Closing state 'cxp2'
14:46:48.26   GAME -- Setting campaign state to 'cxp1'
14:46:48.26   GAME -- Closing state 'cxp1'
14:46:48.26   GAME -- Setting campaign state to 'dlc1'
14:46:48.26   GAME -- Closing state 'dlc1'
14:46:48.26   GAME -- Setting campaign state to 'dlc2'
14:46:48.26   GAME -- Closing state 'dlc2'
14:46:48.28   GAME -- Setting campaign state to 'dlc3'
14:46:48.28   GAME -- Closing state 'dlc3'
14:46:49.81   Activating screen: MessageBoxPopup
14:46:49.81   Created Matchinfo
14:46:49.81   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
14:46:49.82   starting online hosting
14:46:49.84   OnlineHostAsync: initiating CallCreateMatch
14:46:49.98   OnlineHostAsync: created gid=204272670
14:46:50.00   Transport - Largest sent is now 79
14:46:50.01   RendezvousNotifier - Received Participate ParticipationEvent.
14:46:50.10   Transport - Largest received is now 105
14:46:50.26   OnlineHostAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=62782;PID=3263704;RVCID=80805398], private [udp:/address=[IP address redacted];port=6112;PID=3263704]
14:46:50.40   OnlineHostAsync: initiating UpdateSessionURL [gid=204272670, url=udp:/address=[IP address redacted];port=62782;PID=3263704;RVCID=80805398]
14:46:50.60   OnJoinAdvertisementSuccess - joined online match, server leave notification required
14:46:50.60   starting local hosting
14:46:50.60   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:46:50.60   Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:46:50.60   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:46:50.60   Session::Host sid = C2CF41E, hostURL = , local addresses = WINaddr:[IP address redacted]:6112;
14:46:50.60   ValidateCustomData: called with 705 bytes of custom data
14:46:50.60   Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112;
14:46:50.60   AdvertisementInternal::Process - EVENT_NEWPEER
14:46:50.60   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
14:46:50.64   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
14:46:50.64   hosting - Session is connected
14:46:50.64   Net::Session::SetVisible - session is set to INVISIBLE.
14:46:50.65   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:46:50.65   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/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
14:46:50.67   hosting completed successfully
14:46:50.67   HostAsync - completed with HostResult = 0
14:46:50.67   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
14:46:50.67   Activating screen: OnlineGameSetup
14:46:50.67   MessageInternal::CreateChannel: Created channel 534d5347
14:46:50.68   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:46:50.68   MatchInternal::SetMatchType - new type 14 - updating server
14:46:50.70   SetVisible called while !IsConnected
14:46:50.70   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.391359, bars=1, max avg=0.201, sd=0.217, 5 samples =  0.63 0.04 0.12 0.15 0.06
14:46:50.71   Activating screen: RelicOnlineChat
14:46:50.71   Activating screen: RelicOnlineNewsScreen
14:46:50.81   Activating screen: RelicOnlineStatsScreen
14:46:50.81   Activating screen: Achievements
14:46:50.81   GAME -- Setting campaign state to 'cxp2'
14:46:50.81   GAME -- Closing state 'cxp2'
14:46:50.81   GAME -- Setting campaign state to 'cxp1'
14:46:50.81   GAME -- Closing state 'cxp1'
14:46:50.81   GAME -- Setting campaign state to 'dlc1'
14:46:50.81   GAME -- Closing state 'dlc1'
14:46:50.81   GAME -- Setting campaign state to 'dlc2'
14:46:50.81   GAME -- Closing state 'dlc2'
14:46:50.82   GAME -- Setting campaign state to 'dlc3'
14:46:50.82   GAME -- Closing state 'dlc3'
14:46:50.82   GAME -- Setting campaign state to 'dlc1'
14:46:50.82   GAME -- Closing state 'dlc1'
14:46:50.84   Activating screen: GameHistory
14:46:50.84   GAME -- Setting campaign state to 'cxp2'
14:46:50.84   GAME -- Closing state 'cxp2'
14:46:50.84   GAME -- Setting campaign state to 'cxp1'
14:46:50.84   GAME -- Closing state 'cxp1'
14:46:50.84   GAME -- Setting campaign state to 'dlc1'
14:46:50.84   GAME -- Closing state 'dlc1'
14:46:50.84   GAME -- Setting campaign state to 'dlc2'
14:46:50.84   GAME -- Closing state 'dlc2'
14:46:50.84   GAME -- Setting campaign state to 'dlc3'
14:46:50.84   GAME -- Closing state 'dlc3'
14:46:50.84   Activating screen: OnlineGameSetup
14:46:50.84   Activating screen: RelicOnlineTabs
14:46:50.84   AutomatchInternal::OnHostComplete - Completed Host with success=1
14:46:50.84   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
14:46:50.84   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
14:46:50.85   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:46:50.85   Net::Session::SetVisible - session is set to INVISIBLE.
14:46:50.87   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.391359, bars=1, max avg=0.201, sd=0.217, 5 samples =  0.63 0.04 0.12 0.15 0.06
14:46:50.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:46:50.96   GAME -- Setting campaign state to 'cxp2'
14:46:50.96   GAME -- Closing state 'cxp2'
14:46:50.96   GAME -- Setting campaign state to 'cxp1'
14:46:50.96   GAME -- Closing state 'cxp1'
14:46:50.96   GAME -- Setting campaign state to 'dlc1'
14:46:50.96   GAME -- Closing state 'dlc1'
14:46:50.96   GAME -- Setting campaign state to 'dlc2'
14:46:50.96   GAME -- Closing state 'dlc2'
14:46:50.96   GAME -- Setting campaign state to 'dlc3'
14:46:50.96   GAME -- Closing state 'dlc3'
14:46:50.98   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:46:51.04   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:46:51.82   Requesting Relic Downloader soft throttle call [GetNewsAsync] has time 844
14:46:51.82   Sent message game CompanyOfHeroes softthrottle
14:46:51.87   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:46:52.46   GetUserStats requested stats for PIDs ( 708345 2693666 3060958 3113790 3423389 3426494 3446491 3586647 3603588 3610770 3649979 3654615 3679000 3838690 ) (best:14, full:0)
14:46:52.46   QueryMatches: Got [43] maps, [113] ids, [17] advertisements, startID [1]
14:46:52.46   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 96 matches
14:46:53.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:46:53.81   Net::Session::SetVisible - session is set to INVISIBLE.
14:46:53.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:46:53.81   MatchInternal::SetMatchState - state 1 - updating server
14:46:53.81   Net::Session::SetVisible - session is set to INVISIBLE.
14:46:53.81   MatchSetup: Sending start game message!
14:46:53.81   GameSetupForm - Starting game
14:46:53.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:46:53.82   PopulateGameInfo - random seed:[1318250813], guid:[{3bb673ff-7da8-4c9d-9e31-3b64b4b18533}], sync level:[0]
14:46:53.82   Error loading [DATA:levelingCurve.lua]
14:46:53.82   Error loading [DATA:levelingCurve.lua]
14:46:53.82   MOD - Setting player (0) race to: allies
14:46:53.82   MOD - Setting player (0) race to: 1
14:46:53.82   MOD - Setting player (1) race to: axis
14:46:53.82   MOD - Setting player (1) race to: 3
14:46:53.84   OnlineUpdateStateAsync: initiating state change, id = 204272670, state=2
14:46:53.87   APP -- Game Start
14:46:53.87   Sent message game CompanyOfHeroes allowtraffic
14:46:53.87   GAME -- Setting campaign state to 'cxp2'
14:46:53.87   GAME -- Closing state 'cxp2'
14:46:53.87   GAME -- Setting campaign state to 'cxp1'
14:46:53.87   GAME -- Closing state 'cxp1'
14:46:53.87   GAME -- Setting campaign state to 'dlc1'
14:46:53.87   GAME -- Closing state 'dlc1'
14:46:53.89   GAME -- Setting campaign state to 'dlc2'
14:46:53.89   GAME -- Closing state 'dlc2'
14:46:53.89   GAME -- Setting campaign state to 'dlc3'
14:46:53.89   GAME -- Closing state 'dlc3'
14:46:53.89   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:46:53.92   GAME -- Ending FE
14:46:53.92   UIFrontEnd - Unloading Front End
14:46:53.95   SOUND -- Shutting down ...
14:46:54.04   SOUND -- Shutdown completed!
14:46:54.07
14:46:54.07   GAME -- *** Beginning mission challenge_angoville (1 Humans, 1 Computers) ***
14:46:54.07
14:46:54.25   GAME -- Recording game
14:46:54.35   Activating screen: GameLoadScreen
14:46:54.37   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
14:46:55.25   OnlineUpdateStateAsync: updated state for gid=204272670
14:46:55.35   SOUND -- Initializing ...
14:46:56.40   SOUND -- Initialization completed!
14:46:56.45   QueryMatches: Got [44] maps, [114] ids, [17] advertisements, startID [204271409]
14:46:56.45   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 80 matches
14:46:56.45   PHYSICS: detected processor(s) capable of handling 2 threads.
14:46:56.46   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville'
14:46:56.46   MOD -- Using Mod 'Eastern_Front'
14:46:56.50   Unable to load/parse precache file [DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville_precache.lua]
14:46:56.75   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:46:56.75   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:47:02.51   GameObjLoader - upgrading load_count from 0 to 1009
14:47:03.00   QueryMatches: Got [40] maps, [111] ids, [17] advertisements, startID [204272019]
14:47:03.00   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 61 matches
14:47:07.09   GameObjLoader - upgrading load_count from 1009 to 1462
14:47:07.39   PHYSICS -- Created node factory 'HVOK'
14:47:07.39   PHYSICS -- Created node factory 'DMMY'
14:47:08.17   QueryMatches: Got [40] maps, [107] ids, [17] advertisements, startID [204272287]
14:47:08.18   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 41 matches
14:47:13.14   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 937
14:47:13.14   Sent message game CompanyOfHeroes softthrottle
14:47:13.31   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:47:13.79   QueryMatches: Got [42] maps, [112] ids, [17] advertisements, startID [204272458]
14:47:13.81   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 29 matches
14:47:18.00   QueryMatches: Got [43] maps, [112] ids, [17] advertisements, startID [204272587]
14:47:18.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:47:18.01   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 13 matches
14:47:19.48   GameObjLoader - upgrading load_count from 43 to 3327
14:47:22.92   QueryMatches: Got [43] maps, [111] ids, [17] advertisements, startID [204272658]
14:47:26.15   GameObjLoader 07c40fe8 - resetting counters
14:47:26.15   GameObjLoader 07c40fe8 - LOAD_DONE
14:47:26.15   GAME - SessionSetup
14:47:26.50   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
14:47:26.50   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
14:47:26.50   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
14:47:28.87   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:47:30.95   GAME - CreateGEWorld in 4797 ms
14:47:30.95   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
14:47:31.15   GAME - SessionSetup finished in 5000 ms
14:47:31.17   GAME - WaterReflectionManagerSetup
14:47:31.17   GAME - WaterReflectionManagerSetup finished in 0 ms
14:47:31.18   MessageInternal::CreateChannel: Created channel 474d4343
14:47:31.48   GameObj::OnFatalScarError: [string "EASTERN_FRONT\DATA\Scar\EFUtil.scar"]:14: attempt to call a nil value
  main            challenge_angoville.scar       Ln    7 ((null))
14:47:31.48   GAME -- SimulationController::Pause 0
14:47:31.51   GameObj::OnFatalScarError: Error loading script file (DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville.scar).  Check script syntax!
14:47:31.51   GAME -- SimulationController::Pause 1
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:31.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:47:32.39   MessageInternal::CreateChannel: Created channel 4d4f444d
14:47:33.59   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:47:33.60   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:47:33.60   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:47:33.60   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:47:33.60   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:47:34.53   SPEECHMANAGER -- Loaded in 0.840340 seconds
14:47:36.48   GameObj::OnFatalScarError: [string "EASTERN_FRONT\DATA\Scar\EFUtil.scar"]:6: attempt to call global 'ModifyStartingResourcesForRewardFactions' (a nil value)
14:47:36.48   GAME -- SimulationController::Pause 1
14:47:36.51   GameObj::OnFatalScarError: FATAL Scar Error! - Execution has been paused.  Error calling init function OnInit.
14:47:36.51   GAME -- SimulationController::Pause 1
14:47:36.73   GameObjLoader - upgrading load_count from 0 to 407
14:47:38.37   GameObjLoader 07db6d80 - resetting counters
14:47:38.37   GameObjLoader 07db6d80 - LOAD_DONE
14:47:38.59   PreloadResources took 156ms.
14:47:38.62   GAME -- Loading completed (44 seconds)
14:47:38.62   SIM -- Setting SyncErrorChecking level to Low
14:47:39.32   Activating screen: GameScreen
14:47:39.32   Activating screen: Decorators
14:47:39.32   Activating screen: Taskbar
14:47:39.32   Activating screen: SubtitleScreen
14:47:39.32   Activating screen: TextOverlayScreen
14:47:39.39   PerformanceRecorder::StartRecording for game size 2
14:47:39.39   GAME -- Starting mission...
14:47:43.82   Warning: binding repeat_1(Ability: abilities\smg_m3) -- ui index '0' out of bounds; range is [1, 12]
14:47:47.23   Activating screen: Command_Tree
14:47:51.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:47:51.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/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
14:48:01.18   Activating screen: TacticalMap
14:48:01.18   Activating screen: TextOverlayScreen
14:48:02.84   Activating screen: GameScreen
14:48:02.84   Activating screen: Decorators
14:48:02.84   Activating screen: Taskbar
14:48:02.84   Activating screen: SubtitleScreen
14:48:02.84   Activating screen: TextOverlayScreen
14:48:19.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
14:48:20.45   Activating screen: pause_menu
14:48:20.45   GAME -- SimulationController::Pause 1
14:48:22.56   Activating screen: pause_options
14:48:31.81   GAME -- SimulationController::Pause 1
14:48:31.95   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 0) (CmdAI)
14:48:36.84   Warning: non auto-match upgrade not found in AE, tuning
14:48:39.34   Activating screen: Command_Tree
14:48:44.60   Warning: binding repeat_3(Ability: abilities\smg_m3) -- ui index '0' out of bounds; range is [1, 12]
14:48:49.62   Activating screen: TacticalMap
14:48:49.62   Activating screen: TextOverlayScreen
14:48:52.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:48:52.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/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
14:48:53.40   Activating screen: GameScreen
14:48:53.40   Activating screen: Decorators
14:48:53.40   Activating screen: Taskbar
14:48:53.40   Activating screen: SubtitleScreen
14:48:53.40   Activating screen: TextOverlayScreen
14:49:02.84   Activating screen: TacticalMap
14:49:02.84   Activating screen: TextOverlayScreen
14:49:04.76   Activating screen: GameScreen
14:49:04.76   Activating screen: Decorators
14:49:04.76   Activating screen: Taskbar
14:49:04.76   Activating screen: SubtitleScreen
14:49:04.76   Activating screen: TextOverlayScreen
14:49:07.32   Activating screen: TacticalMap
14:49:07.32   Activating screen: TextOverlayScreen
14:49:12.87   Activating screen: GameScreen
14:49:12.87   Activating screen: Decorators
14:49:12.87   Activating screen: Taskbar
14:49:12.87   Activating screen: SubtitleScreen
14:49:12.87   Activating screen: TextOverlayScreen
14:49:13.79   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
14:49:20.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
14:49:27.42   Activating screen: pause_menu
14:49:27.42   GAME -- SimulationController::Pause 0
14:49:29.53   Activating screen: prompt_generic
14:49:30.81   MOD -- Player jwvd1 (network abort) (frame 403) (KillPlayer)
14:49:30.81   MOD -- Could not find win condition 'annihilate'
14:49:30.81   MOD -- Could not find win condition 'annihilate'
14:49:30.81   MOD -- Game Over at frame 403
14:49:30.81   GameApp::ReportStats
14:49:30.81   ReportMatchResults - onlyReportOnWin = 0
14:49:30.81   ReportMatchResults - SimID:1001, raceID:0, teamID:0, uid:0:3263704, result:1:PS_ABORTED
14:49:30.81   ReportMatchResults - SimID:1000, raceID:1, teamID:1, uid:0:0, AI player, ignoring
14:49:30.81   ReportMatchResults - reporting normal game results for match 0:204272670
14:49:30.81   ReportSimStats - storing simulation results for match 0:204272670
14:49:30.81   ReportStats - ReportSimStats failed
14:49:30.81   GameObj::DoGameOverPopup
14:49:30.81   Activating screen: GameOverDialog
14:49:30.81   GAME -- SimulationController::Pause 1
14:49:30.81   ArbitrationMessageNeeded 0 allies vs 0 enemies result 0
14:49:30.81   GameOverDialog - normal game ending (no sync errors)
14:49:30.81   OnlineReportResults - player=3263704, gid = 204272670, time=50, result=1, team=0, race=0, simPlayerID=1001
14:49:30.81   OnlineReportResults: Call started.
14:49:31.32   RendezvousNotifier - StatsUpdate MatchEvent - flushing player stats for pid = 3263704.
14:49:31.32   RNT_StatsUpdate: Loss notification, userID [00000000:0031ccd8], matchType=14, race =0, level+=0
14:49:31.32   FEAppListener - Processing win/loss notification
14:49:31.32   RendezvousNotifier - received report simulation stats notification, sending simulation stats to lobby.
14:49:31.32   PostStoredStats: matchID = [00000000:0c2cf41e] is unknown
14:49:31.56   GetUserStats requested stats for PIDs ( 3263704 ) (best:0, full:1)
14:49:31.62   Activating screen: ViewStats
14:49:32.32   Activating screen: TacticalMap
14:49:32.32   Activating screen: TextOverlayScreen
14:49:33.79   Activating screen: GameScreen
14:49:33.79   Activating screen: Decorators
14:49:33.79   Activating screen: Taskbar
14:49:33.79   Activating screen: SubtitleScreen
14:49:33.79   Activating screen: TextOverlayScreen
14:49:34.81   Activating screen: postgame_stats_skirm
14:49:36.95   Activating screen: AppRestartingForm
14:49:36.95   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:49:36.95   Widget attempting to remove a non-existance listener. Widget:bkg_COH
14:49:36.95   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
14:49:36.95   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
14:49:36.95   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
14:49:36.95   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
14:49:36.98   APP -- Game Stop
14:49:36.98   GameApp::TransitionGameStop
14:49:36.98   GameApp::TransitionQuitCommon
14:49:36.98   PerformanceRecorder: Updating profile history
14:49:36.98   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:36.98   PerformanceRecorder::EndRecording - game size=2, max average=0.036969, worst frame=0.416482
14:49:36.98    Recording: No [2 players]
14:49:36.98    Total render, sim time: 16.58, 16.58 sec
14:49:36.98    Total render, sim frames: 531, 228
14:49:36.98    Max/Avg: 0.04, 0.02 sec (fps=27.05, 43.37) (3 samples)
14:49:36.98    Bars: 5
14:49:36.98   Profile Max Performance[2 players] =  0.04 0.63 0.04 0.12 0.15
14:49:36.98   Profile Avg Performance[2 players] =  0.02 0.08 0.02 0.07 0.06
14:49:36.98   GameObj::ShutdownGameObj
14:49:36.98   Calling disconnect...
14:49:36.98   Session is marking the local peer PeerID 1 for death
14:49:36.98   OnlineLeaveAdvertisementAsync: Match id = 204272670 started, nothing to do
14:49:36.98   Session::GetEvent DestroyPeer success Peer 1 removed
14:49:36.98   Session::DestroyPeer - deallocating peer 1
14:49:36.98   Session - Requested Disconnect for connectionID 2
14:49:36.98   Deallocated route ID=3 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:49:36.98   Session - Requested Disconnect for connectionID 3
14:49:36.98   Closed route 3<>3 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:49:36.98   AdvertisementInternal::Process - EVENT_DESTROYPEER
14:49:36.98   LeaveMatchInternal - no action taken, needLeave=0, service=0
14:49:36.98   Destroyed Matchinfo
14:49:36.98   GAME -- Ending mission (Game over)
14:49:36.98   GameObjLoader 07db6d80 - Unload
14:49:36.98   GameObjLoader 07db6d80 - resetting counters
14:49:37.03   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
14:49:37.09   GameObjLoader 07c40fe8 - Unload
14:49:37.09   GameObjLoader 07c40fe8 - resetting counters
14:49:37.17   SOUND -- Shutting down ...
14:49:37.26   SOUND -- Shutdown completed!
14:49:37.26   MessageInternal::DestroyChannel: Destroyed channel 474d4343
14:49:37.26   GAME -- Beginning FE
14:49:37.26   Sent message game CompanyOfHeroes allowtraffic
14:49:37.26   UIFrontEnd - Loading Front End
14:49:37.31   SOUND -- Initializing ...
14:49:37.37   SOUND -- Initialization completed!
14:49:37.37   UIFrontEnd - Initializing Forms
14:49:40.23   CampaignFilter::BindFilterSpecificWidgets()
14:49:40.32   Turning off auto-selection of profiles, we already have a profile selected.
14:49:40.34   MOD -- Mounting EF_DLC1, 1.0
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\Data\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtLow\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\DataArtLow\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\DataSoundEnglish\'
14:49:40.34   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\Movies\'
14:49:40.34   MOD -- Mounting EF_DLC2, 1.0
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtLow\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\DataArtLow\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\DataSoundEnglish\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\Movies\'
14:49:40.35   MOD -- Mounting EF_DLC3, 1.0
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtLow\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\DataArtLow\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\DataSoundEnglish\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\Movies\'
14:49:40.35   MOD -- Mounting EF_OPS, 1.0
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Ops\Data\'
14:49:40.35   MOD -- Mounting Eastern_Front, 1.0
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\RelicOnline\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Engine\Data\'
14:49:40.35   FILESYSTEM -- filepath failure, path does not exist 'WW2\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtLow\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\DataArtLow\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataSoundLow\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataSound\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\'
14:49:40.37   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Engine\Locale\English\Data\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\DataSoundEnglish\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Locale\English\Data\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataAttrib\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Movies\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Engine\Movies\'
14:49:40.42   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\WW2\Movies\'
14:49:40.43   Activating screen: AppRestartingForm
14:49:40.43   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:49:40.43   OnlineLeaveAdvertisementAsync: Match id = 204272670 started, nothing to do
14:49:40.43   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
14:49:40.79   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:49:40.79   PeerRemoveAll - flushing local session peer data
14:49:40.79   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
14:49:41.62   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:49:41.62   CRC & Version Info : 0000025a:5f21ef68:ec3c9e0d eastern_front:602:factionfix.dll 1
14:49:41.62   Activating screen: FEMovie
14:49:41.62   Activating screen: OnlineWidget
14:49:41.62   Activating screen: FE_mm_01
14:49:41.68   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:49:41.68   GAME -- Setting campaign state to 'cxp2'
14:49:41.68   GAME -- Closing state 'cxp2'
14:49:41.68   GAME -- Setting campaign state to 'cxp1'
14:49:41.68   GAME -- Closing state 'cxp1'
14:49:41.68   GAME -- Setting campaign state to 'dlc1'
14:49:41.68   GAME -- Closing state 'dlc1'
14:49:41.68   GAME -- Setting campaign state to 'dlc2'
14:49:41.68   GAME -- Closing state 'dlc2'
14:49:41.68   GAME -- Setting campaign state to 'dlc3'
14:49:41.68   GAME -- Closing state 'dlc3'
14:49:43.06   Activating screen: MessageBoxPopup
14:49:43.06   Created Matchinfo
14:49:43.06   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
14:49:43.07   starting online hosting
14:49:43.09   OnlineHostAsync: initiating CallCreateMatch
14:49:43.25   OnlineHostAsync: created gid=204273026
14:49:43.28   RendezvousNotifier - Received Participate ParticipationEvent.
14:49:43.54   OnlineHostAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=62913;PID=3263704;RVCID=80805398], private [udp:/address=[IP address redacted];port=6112;PID=3263704]
14:49:43.67   OnlineHostAsync: initiating UpdateSessionURL [gid=204273026, url=udp:/address=[IP address redacted];port=62913;PID=3263704;RVCID=80805398]
14:49:43.87   OnJoinAdvertisementSuccess - joined online match, server leave notification required
14:49:43.87   starting local hosting
14:49:43.87   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:49:43.87   Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:49:43.87   Transport::OpenInternal request to WINaddr:[IP address redacted]:6112;
14:49:43.87   Session::Host sid = C2CF582, hostURL = , local addresses = WINaddr:[IP address redacted]:6112;
14:49:43.87   ValidateCustomData: called with 705 bytes of custom data
14:49:43.87   Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112;
14:49:43.87   AdvertisementInternal::Process - EVENT_NEWPEER
14:49:43.87   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
14:49:43.90   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
14:49:43.90   hosting - Session is connected
14:49:43.90   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:43.92   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:49:43.92   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/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
14:49:43.93   hosting completed successfully
14:49:43.93   HostAsync - completed with HostResult = 0
14:49:43.93   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
14:49:43.93   Activating screen: OnlineGameSetup
14:49:43.93   MessageInternal::CreateChannel: Created channel 534d5347
14:49:43.93   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:43.93   MatchInternal::SetMatchType - new type 14 - updating server
14:49:43.96   SetVisible called while !IsConnected
14:49:43.96   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:43.96   Activating screen: RelicOnlineChat
14:49:43.96   Activating screen: RelicOnlineNewsScreen
14:49:44.06   Activating screen: RelicOnlineStatsScreen
14:49:44.06   Activating screen: Achievements
14:49:44.06   GAME -- Setting campaign state to 'cxp2'
14:49:44.06   GAME -- Closing state 'cxp2'
14:49:44.06   GAME -- Setting campaign state to 'cxp1'
14:49:44.06   GAME -- Closing state 'cxp1'
14:49:44.06   GAME -- Setting campaign state to 'dlc1'
14:49:44.06   GAME -- Closing state 'dlc1'
14:49:44.06   GAME -- Setting campaign state to 'dlc2'
14:49:44.06   GAME -- Closing state 'dlc2'
14:49:44.06   GAME -- Setting campaign state to 'dlc3'
14:49:44.06   GAME -- Closing state 'dlc3'
14:49:44.07   GAME -- Setting campaign state to 'dlc1'
14:49:44.07   GAME -- Closing state 'dlc1'
14:49:44.07   Activating screen: GameHistory
14:49:44.07   GAME -- Setting campaign state to 'cxp2'
14:49:44.09   GAME -- Closing state 'cxp2'
14:49:44.09   GAME -- Setting campaign state to 'cxp1'
14:49:44.09   GAME -- Closing state 'cxp1'
14:49:44.09   GAME -- Setting campaign state to 'dlc1'
14:49:44.09   GAME -- Closing state 'dlc1'
14:49:44.09   GAME -- Setting campaign state to 'dlc2'
14:49:44.09   GAME -- Closing state 'dlc2'
14:49:44.09   GAME -- Setting campaign state to 'dlc3'
14:49:44.09   GAME -- Closing state 'dlc3'
14:49:44.09   Activating screen: OnlineGameSetup
14:49:44.09   Activating screen: RelicOnlineTabs
14:49:44.09   AutomatchInternal::OnHostComplete - Completed Host with success=1
14:49:44.09   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
14:49:44.09   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
14:49:44.10   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:44.10   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:44.10   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:44.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:44.18   GAME -- Setting campaign state to 'cxp2'
14:49:44.18   GAME -- Closing state 'cxp2'
14:49:44.18   GAME -- Setting campaign state to 'cxp1'
14:49:44.18   GAME -- Closing state 'cxp1'
14:49:44.18   GAME -- Setting campaign state to 'dlc1'
14:49:44.18   GAME -- Closing state 'dlc1'
14:49:44.18   GAME -- Setting campaign state to 'dlc2'
14:49:44.18   GAME -- Closing state 'dlc2'
14:49:44.18   GAME -- Setting campaign state to 'dlc3'
14:49:44.18   GAME -- Closing state 'dlc3'
14:49:44.20   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:49:44.29   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:44.92   QueryMatches: Got [38] maps, [102] ids, [17] advertisements, startID [204266738]
14:49:44.93   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 39 matches
14:49:45.60   Activating screen: DynamicPopupMenu
14:49:46.39   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:46.39   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:46.39   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:46.42   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:46.50   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:49.28   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:49:49.28   MatchInternal::SetMatchType - new type 17 - updating server
14:49:49.28   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:49.31   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:49:49.31   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:49.31   GetMaxFrameTimeFromProfile: players=8 expected FPS=1.630746, bars=1, max avg=0.368, sd=0.245, 5 samples =  0.34 0.21 0.14 0.32 0.84
14:49:49.42   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:49.45   UpdateMatch: Call to UpdateGathering started, matchTypeID = 17.
14:49:49.45   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:49:49.45   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:49.45   GetMaxFrameTimeFromProfile: players=8 expected FPS=1.630746, bars=1, max avg=0.368, sd=0.245, 5 samples =  0.34 0.21 0.14 0.32 0.84
14:49:49.50   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:49.57   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:49.85   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 828
14:49:49.85   Sent message game CompanyOfHeroes softthrottle
14:49:49.92   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:49:51.06   Requesting Relic Downloader hard throttle call [GetAdvertisementsAsync] has time 2031
14:49:51.06   Sent message game CompanyOfHeroes hardthrottle
14:49:51.10   Got dlman msg [ack game CompanyOfHeroes hardthrottle]
14:49:51.17   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:51.17   MatchInternal::SetMatchType - new type 14 - updating server
14:49:51.17   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:51.18   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:51.18   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:51.18   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:51.28   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:51.35   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:51.89   QueryMatches: Got [37] maps, [104] ids, [17] advertisements, startID [204272032]
14:49:51.89   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 41 matches
14:49:53.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:53.81   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:53.81   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:53.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:53.87   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:49:53.92   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:54.64   QueryMatches: Got [36] maps, [102] ids, [17] advertisements, startID [204272425]
14:49:54.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 39 matches
14:49:59.03   Activating screen: DynamicPopupMenu
14:49:59.67   QueryMatches: Got [37] maps, [101] ids, [17] advertisements, startID [204272633]
14:49:59.67   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
14:49:59.79   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:49:59.79   Net::Session::SetVisible - session is set to INVISIBLE.
14:49:59.79   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:49:59.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:49:59.90   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:50:01.35   Activating screen: RaceSelectionPopup
14:50:02.12   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:50:02.12   Net::Session::SetVisible - session is set to INVISIBLE.
14:50:02.12   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:50:02.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:50:02.25   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:50:03.42   Activating screen: RaceSelectionPopup
14:50:04.87   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 813
14:50:04.87   Sent message game CompanyOfHeroes softthrottle
14:50:04.93   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:50:05.25   QueryMatches: Got [40] maps, [104] ids, [17] advertisements, startID [204272822]
14:50:05.25   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 17 matches
14:50:05.65   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:50:05.65   Net::Session::SetVisible - session is set to INVISIBLE.
14:50:05.65   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:50:05.65   MatchInternal::SetMatchState - state 1 - updating server
14:50:05.65   Net::Session::SetVisible - session is set to INVISIBLE.
14:50:05.65   MatchSetup: Sending start game message!
14:50:05.65   GameSetupForm - Starting game
14:50:05.65   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:50:05.65   PopulateGameInfo - random seed:[1318251005], guid:[{1ba29711-d0d6-422b-855f-893894d27938}], sync level:[0]
14:50:05.67   Error loading [DATA:levelingCurve.lua]
14:50:05.67   Error loading [DATA:levelingCurve.lua]
14:50:05.67   MOD - Setting player (0) race to: allies_soviets
14:50:05.67   MOD - Setting player (0) race to: 2
14:50:05.67   MOD - Setting player (1) race to: axis
14:50:05.67   MOD - Setting player (1) race to: 3
14:50:05.68   OnlineUpdateStateAsync: initiating state change, id = 204273026, state=2
14:50:05.73   APP -- Game Start
14:50:05.73   Sent message game CompanyOfHeroes allowtraffic
14:50:05.73   GAME -- Setting campaign state to 'cxp2'
14:50:05.73   GAME -- Closing state 'cxp2'
14:50:05.73   GAME -- Setting campaign state to 'cxp1'
14:50:05.73   GAME -- Closing state 'cxp1'
14:50:05.73   GAME -- Setting campaign state to 'dlc1'
14:50:05.73   GAME -- Closing state 'dlc1'
14:50:05.73   GAME -- Setting campaign state to 'dlc2'
14:50:05.73   GAME -- Closing state 'dlc2'
14:50:05.73   GAME -- Setting campaign state to 'dlc3'
14:50:05.73   GAME -- Closing state 'dlc3'
14:50:05.73   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:50:05.78   GAME -- Ending FE
14:50:05.78   UIFrontEnd - Unloading Front End
14:50:05.81   SOUND -- Shutting down ...
14:50:05.90   SOUND -- Shutdown completed!
14:50:05.93
14:50:05.93   GAME -- *** Beginning mission challenge_angoville (1 Humans, 1 Computers) ***
14:50:05.93
14:50:06.12   GAME -- Recording game
14:50:06.23   Activating screen: GameLoadScreen
14:50:06.26   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
14:50:06.46   OnlineUpdateStateAsync: updated state for gid=204273026
14:50:06.56   SOUND -- Initializing ...
14:50:07.60   SOUND -- Initialization completed!
14:50:07.65   PHYSICS: detected processor(s) capable of handling 2 threads.
14:50:07.67   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville'
14:50:07.67   MOD -- Using Mod 'Eastern_Front'
14:50:07.68   Unable to load/parse precache file [DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville_precache.lua]
14:50:07.79   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m07_ob2_findrocketlo_load.smf'.  Expensive operation
14:50:07.81   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m07_ob2_findrocketlo_load.smf'.  Expensive operation
14:50:13.71   GameObjLoader - upgrading load_count from 0 to 1028
14:50:14.18   QueryMatches: Got [40] maps, [103] ids, [17] advertisements, startID [204272972]
14:50:14.18   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
14:50:18.35   GameObjLoader - upgrading load_count from 1028 to 1531
14:50:18.79   QueryMatches: Got [41] maps, [107] ids, [17] advertisements, startID [204273078]
14:50:20.37   Transport - Largest sent is now 80
14:50:21.01   Transport - median kBPS [hi/cur] sent = 0.2/0.2, recvd = 0.2/0.2, #p/sec[s/r] = 0.1/0.1, max unsent 0, version err 0, merge 0
14:50:39.96   GameObjLoader - upgrading load_count from 1175 to 3786
14:50:43.00   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m07_ob2_findrocketlo_load.smf'.  Expensive operation
14:50:44.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:50:44.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/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
14:50:44.59   RNT_ReportPing: user 1538791:ThePurplelemon CANNOT connect pingMS=65535
14:50:49.67   GameObjLoader 07c40fe8 - resetting counters
14:50:49.67   GameObjLoader 07c40fe8 - LOAD_DONE
14:50:49.67   GAME - SessionSetup
14:50:49.81   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
14:50:49.81   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
14:50:49.81   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
14:50:54.87   GAME - CreateGEWorld in 5203 ms
14:50:54.87   GAME - SessionSetup finished in 5203 ms
14:50:54.87   GAME - WaterReflectionManagerSetup
14:50:54.87   GAME - WaterReflectionManagerSetup finished in 0 ms
14:50:54.89   MessageInternal::CreateChannel: Created channel 474d4343
14:50:55.14   GameObj::OnFatalScarError: [string "EASTERN_FRONT\DATA\Scar\EFUtil.scar"]:14: attempt to call a nil value
  main            challenge_angoville.scar       Ln    7 ((null))
14:50:55.14   GAME -- SimulationController::Pause 0
14:50:55.17   GameObj::OnFatalScarError: Error loading script file (DATA:scenarios\mp\classic\2p_angoville farms\challenge_angoville.scar).  Check script syntax!
14:50:55.17   GAME -- SimulationController::Pause 1
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:55.51   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:50:56.39   MessageInternal::CreateChannel: Created channel 4d4f444d
14:50:57.67   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:50:57.67   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:50:57.67   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:50:57.67   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:50:57.67   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:50:59.03   SPEECHMANAGER -- Loaded in 1.231456 seconds
14:51:00.67   GameObj::OnFatalScarError: [string "EASTERN_FRONT\DATA\Scar\EFUtil.scar"]:6: attempt to call global 'ModifyStartingResourcesForRewardFactions' (a nil value)
14:51:00.67   GAME -- SimulationController::Pause 1
14:51:00.73   GameObj::OnFatalScarError: FATAL Scar Error! - Execution has been paused.  Error calling init function OnInit.
14:51:00.73   GAME -- SimulationController::Pause 1
14:51:00.95   GameObjLoader - upgrading load_count from 0 to 390
14:51:02.45   GameObjLoader 07db6d80 - resetting counters
14:51:02.45   GameObjLoader 07db6d80 - LOAD_DONE
14:51:02.78   PreloadResources took 266ms.
14:51:02.84   GAME -- Loading completed (57 seconds)
14:51:02.84   SIM -- Setting SyncErrorChecking level to Low
14:51:04.48   Activating screen: GameScreen
14:51:04.48   Activating screen: Decorators
14:51:04.48   Activating screen: Taskbar
14:51:04.48   Activating screen: SubtitleScreen
14:51:04.48   Activating screen: TextOverlayScreen
14:51:04.51   PerformanceRecorder::StartRecording for game size 2
14:51:04.51   GAME -- Starting mission...
14:51:07.81   Activating screen: Command_Tree
14:51:10.00   Activating screen: Command_Branch
14:51:15.50   Activating screen: pause_menu
14:51:15.50   GAME -- SimulationController::Pause 1
14:51:17.51   GAME -- SimulationController::Pause 1
14:51:17.65   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 0) (CmdAI)
14:51:19.42   Warning: binding repeat_2(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
14:51:19.46   Warning: binding repeat_5(Ability: abilities\smg_mp40) -- ui index '0' out of bounds; range is [1, 12]
14:51:22.01   Transport - median kBPS [hi/cur] sent = 0.2/0.0, recvd = 0.2/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0
14:51:22.92   Warning: non auto-match upgrade not found in AE, tuning
14:51:26.29   Activating screen: pause_menu
14:51:26.29   GAME -- SimulationController::Pause 0
14:51:27.31   Activating screen: prompt_generic
14:51:27.98   MOD -- Player jwvd1 (network abort) (frame 60) (KillPlayer)
14:51:27.98   MOD -- Could not find win condition 'annihilate'
14:51:27.98   MOD -- Could not find win condition 'annihilate'
14:51:27.98   MOD -- Game Over at frame 60
14:51:27.98   GameApp::ReportStats
14:51:27.98   ReportMatchResults - onlyReportOnWin = 0
14:51:27.98   ReportMatchResults - SimID:1000, raceID:5, teamID:0, uid:0:0, AI player, ignoring
14:51:27.98   ReportMatchResults - SimID:1001, raceID:1, teamID:1, uid:0:3263704, result:1:PS_ABORTED
14:51:27.98   ReportMatchResults - reporting normal game results for match 0:204273026
14:51:27.98   ReportSimStats - storing simulation results for match 0:204273026
14:51:27.98   ReportStats - ReportSimStats failed
14:51:27.98   GameObj::DoGameOverPopup
14:51:27.98   Activating screen: GameOverDialog
14:51:27.98   GAME -- SimulationController::Pause 1
14:51:27.98   ArbitrationMessageNeeded 0 allies vs 0 enemies result 0
14:51:27.98   GameOverDialog - normal game ending (no sync errors)
14:51:28.00   OnlineReportResults - player=3263704, gid = 204273026, time=7, result=1, team=1, race=1, simPlayerID=1001
14:51:28.00   OnlineReportResults: Call started.
14:51:28.81   Requesting Relic Downloader soft throttle call [OnlineReportResults] has time 812
14:51:28.81   Sent message game CompanyOfHeroes softthrottle
14:51:28.85   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:51:28.92   Activating screen: ViewStats
14:51:29.14   RendezvousNotifier - StatsUpdate MatchEvent - flushing player stats for pid = 3263704.
14:51:29.14   RNT_StatsUpdate: Loss notification, userID [00000000:0031ccd8], matchType=14, race =1, level+=0
14:51:29.14   FEAppListener - Processing win/loss notification
14:51:29.14   RendezvousNotifier - received report simulation stats notification, sending simulation stats to lobby.
14:51:29.14   PostStoredStats: matchID = [00000000:0c2cf582] is unknown
14:51:29.65   Activating screen: TacticalMap
14:51:29.65   Activating screen: TextOverlayScreen
14:51:29.78   GetUserStats requested stats for PIDs ( 3263704 ) (best:0, full:1)
14:51:31.17   Activating screen: GameScreen
14:51:31.17   Activating screen: Decorators
14:51:31.17   Activating screen: Taskbar
14:51:31.17   Activating screen: SubtitleScreen
14:51:31.17   Activating screen: TextOverlayScreen
14:51:32.34   Activating screen: postgame_stats_skirm
14:51:35.10   Activating screen: AppRestartingForm
14:51:35.10   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:51:35.10   Widget attempting to remove a non-existance listener. Widget:bkg_COH
14:51:35.10   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
14:51:35.10   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
14:51:35.10   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
14:51:35.10   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
14:51:35.14   APP -- Game Stop
14:51:35.14   GameApp::TransitionGameStop
14:51:35.14   GameApp::TransitionQuitCommon
14:51:35.14   PerformanceRecorder: Not enough game time to update profile history
14:51:35.14   GetMaxFrameTimeFromProfile: players=2 expected FPS=2.401062, bars=1, max avg=0.196, sd=0.221, 5 samples =  0.04 0.63 0.04 0.12 0.15
14:51:35.14   PerformanceRecorder::EndRecording - game size=2, max average=0.000000, worst frame=0.416482
14:51:35.14    Recording: No [2 players]
14:51:35.14    Total render, sim time: 10.27, 9.49 sec
14:51:35.14    Total render, sim frames: 268, 19
14:51:35.14    Max/Avg: 0.00, 0.00 sec (fps=0.00, 0.00) (1 samples)
14:51:35.14    Bars: 0
14:51:35.14   Profile Max Performance[2 players] =  0.04 0.63 0.04 0.12 0.15
14:51:35.14   Profile Avg Performance[2 players] =  0.02 0.08 0.02 0.07 0.06
14:51:35.14   GameObj::ShutdownGameObj
14:51:35.14   Calling disconnect...
14:51:35.14   Session is marking the local peer PeerID 1 for death
14:51:35.14   OnlineLeaveAdvertisementAsync: Match id = 204273026 started, nothing to do
14:51:35.14   Session::GetEvent DestroyPeer success Peer 1 removed
14:51:35.14   Session::DestroyPeer - deallocating peer 1
14:51:35.14   Session - Requested Disconnect for connectionID 4
14:51:35.14   Deallocated route ID=5 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:51:35.14   Session - Requested Disconnect for connectionID 5
14:51:35.14   Closed route 5<>5 for PeerID 1 at WINaddr:[IP address redacted]:6112;
14:51:35.14   AdvertisementInternal::Process - EVENT_DESTROYPEER
14:51:35.14   LeaveMatchInternal - no action taken, needLeave=0, service=0
14:51:35.14   Destroyed Matchinfo
14:51:35.14   GAME -- Ending mission (Game over)
14:51:35.14   GameObjLoader 07db6d80 - Unload
14:51:35.14   GameObjLoader 07db6d80 - resetting counters
14:51:35.18   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
14:51:35.25   GameObjLoader 07c40fe8 - Unload
14:51:35.25   GameObjLoader 07c40fe8 - resetting counters
14:51:35.32   SOUND -- Shutting down ...
14:51:35.42   SOUND -- Shutdown completed!
14:51:35.42   MessageInternal::DestroyChannel: Destroyed channel 474d4343
14:51:35.42   GAME -- Beginning FE
14:51:35.42   Sent message game CompanyOfHeroes allowtraffic
14:51:35.42   UIFrontEnd - Loading Front End
14:51:35.46   SOUND -- Initializing ...
14:51:35.53   SOUND -- Initialization completed!
14:51:35.53   UIFrontEnd - Initializing Forms
14:51:38.54   CampaignFilter::BindFilterSpecificWidgets()
14:51:38.64   Turning off auto-selection of profiles, we already have a profile selected.
14:51:38.65   MOD -- Mounting EF_DLC1, 1.0
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC1\Movies\'
14:51:38.65   MOD -- Mounting EF_DLC2, 1.0
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC2\Movies\'
14:51:38.65   MOD -- Mounting EF_DLC3, 1.0
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\DataArtLow\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\DataSoundEnglish\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\DLC3\Movies\'
14:51:38.65   MOD -- Mounting EF_OPS, 1.0
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Ops\Data\'
14:51:38.65   MOD -- Mounting Eastern_Front, 1.0
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\RelicOnline\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Engine\Data\'
14:51:38.65   FILESYSTEM -- filepath failure, path does not exist 'WW2\Data\'
14:51:38.67   FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\'
14:51:38.67   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Data\'
14:51:38.67   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Data\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtLow\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\DataArtLow\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataSoundLow\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataSound\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\'
14:51:38.68   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Engine\Locale\English\Data\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\DataSoundEnglish\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\CoH\Locale\English\Data\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\DataAttrib\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Eastern_Front\Movies\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\Engine\Movies\'
14:51:38.71   FILESYSTEM -- filepath failure, path does not exist 'C:\Documents and Settings\jan willem\Mijn documenten\My Games\Company of Heroes\WW2\Movies\'
14:51:38.75   Activating screen: AppRestartingForm
14:51:38.75   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:51:38.75   OnlineLeaveAdvertisementAsync: Match id = 204273026 started, nothing to do
14:51:38.75   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
14:51:39.17   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:51:39.17   PeerRemoveAll - flushing local session peer data
14:51:39.17   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
14:51:40.01   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:51:40.01   CRC & Version Info : 0000025a:5f21ef68:ec3c9e0d eastern_front:602:factionfix.dll 1
14:51:40.01   Activating screen: FEMovie
14:51:40.01   Activating screen: OnlineWidget
14:51:40.01   Activating screen: FE_mm_01
14:51:40.03   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:51:40.03   GAME -- Setting campaign state to 'cxp2'
14:51:40.03   GAME -- Closing state 'cxp2'
14:51:40.03   GAME -- Setting campaign state to 'cxp1'
14:51:40.03   GAME -- Closing state 'cxp1'
14:51:40.03   GAME -- Setting campaign state to 'dlc1'
14:51:40.03   GAME -- Closing state 'dlc1'
14:51:40.03   GAME -- Setting campaign state to 'dlc2'
14:51:40.03   GAME -- Closing state 'dlc2'
14:51:40.03   GAME -- Setting campaign state to 'dlc3'
14:51:40.03   GAME -- Closing state 'dlc3'
14:51:42.07   Activating screen: QuitPromptDialog
14:51:42.75   GameApp::Quit( exitValue = 0 )
14:51:42.78   GameApp::ShutFrontEnd
14:51:42.81   GAME -- Ending FE
14:51:42.81   UIFrontEnd - Unloading Front End
14:51:42.82   SOUND -- Shutting down ...
14:51:42.95   SOUND -- Shutdown completed!
14:51:43.01   ~FEAppListener - Flushing queued online notifications
14:51:43.45   DLLDriverLinker -- 1 DLL drivers found.
14:51:43.45   ActionDatabase : [2344] global actions, [8] dummy actions.
14:51:43.46   NetworkManager::Destroy - destroying network manager
14:51:43.46   RendezvousManager::Shutdown - deleting instance
14:51:43.46   RendezvousManager - terminating all server calls in progress
14:51:43.46   CallManager - terminating all server calls in progress (0 in progress)
14:51:43.46   RendezvousManager - destroying chat handler
14:51:43.46   RendezvousManager::DestroyNATTraversalClient - NAT traversal no longer available.
14:51:43.46   ~RendezvousManager - disconnecting from server
14:51:43.64   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
14:51:43.64   MessageInternal::DestroyChannel: Destroyed channel 50525459
14:51:43.64   PartyInternal: Fade to black
14:51:43.64   AutomatchInternal: Fade to black
14:51:43.64   PeerRemoveAll - flushing local session peer data
14:51:43.64   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
14:51:43.64   MessageInternal::DestroyChannel: Destroyed channel 47535450
14:51:43.64   Session - Requested Disconnect for connectionID 1
14:51:43.64   SessionManager::UnregisterSession - Unregistering session 0ec3d0a0
14:51:43.64   SessionManager::~SessionManager - uninitializing
14:51:43.65   Net::ThreadFunction - Returning from the network thread function...
14:51:43.67   Transport::~Transport - shutting down transport
14:51:43.67   Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112;
14:51:43.67   Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112;
14:51:43.71   Wrote 688 bytes to network datastore login_cache.bin
14:51:43.73   Wrote 120560 bytes to network datastore global_cache.bin
14:51:43.78   Wrote 657874 bytes to network datastore ping_cache.bin
14:51:43.78   Datastore -- uninitialize complete
14:51:43.78   QuazalInitializer - static uninit of Quazal library
14:51:43.78   QuazalInitializer - unhooking memory 0 currently allocated, 32509 allocations, total of 10800340 bytes