-- Log file for all dbTracef messages --

17:32:23.20   NetworkManager::Create - creating network manager
17:32:23.20   Read 269 bytes from network datastore login_cache.bin
17:32:23.21   Read 52 bytes from network datastore global_cache.bin
17:32:23.21   Read 100 bytes from network datastore ping_cache.bin
17:32:23.21   QuazalInitializer - static initializing Quazal library
17:32:23.21   PingCache - populating cache with 4 pings
17:32:23.27   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
17:32:23.32   WinTransport - CreateSocket exclusive broadcast socket was available.
17:32:23.32   WinTransport - CreateSocket listening for broadcasts on default port
17:32:23.33   WinTransport - Host Name: NgocQuang-PC, aliases: , type=AF_INET, len=4
17:32:23.33   WinTransport - Host IP Address #0: 5.162.102.231
17:32:23.33   WinTransport - WARNING, MultipleNetwork adapters may cause problems
17:32:23.33   WinTransport - Interface #0: ip:5.162.102.231, broadcast:5.162.102.231, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
17:32:23.33   WinTransport - Interface #1: ip:192.168.1.101, broadcast:192.168.1.101, flags=IFF_BROADCAST IFF_MULTICAST
17:32:23.33   WinTransport - Interface #2: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
17:32:23.33   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:32:23.33   WinTransport - Quazal address string = udp:/address=5.162.102.231;port=6112
17:32:23.33   SessionManager - Peer Header Size = 16 bytes
17:32:23.33   SessionManager - Game Data overhead = 7 bytes
17:32:23.33   SessionManager - Proxy overhead = 7 bytes
17:32:23.33   MessageInternal::CreateChannel: Created channel 47535450
17:32:23.33   Session::Initialize - info, initializing session object, using threads.
17:32:23.33   SessionManager::RegisterSession - Registering new session 07832f50
17:32:23.33   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:32:23.33   AutomatchInternal: Instantiating
17:32:23.33   PartyInternal: Instantiating
17:32:23.33   MessageInternal::CreateChannel: Created channel 50525459
17:32:23.33   MessageInternal::CreateChannel: Created channel 51434b4d
17:32:23.33   Net::ThreadFunction - Entering network thread function...
17:32:23.33   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:32:23.33   MessageInternal::CreateChannel: Created channel 534d5347
17:32:23.33   MessageInternal::CreateChannel: Created channel 474d4343
17:32:23.33   MessageInternal::CreateChannel: Created channel 474f424a
17:32:23.33   MessageInternal::CreateChannel: Created channel 4d4f444d
17:32:23.33   MessageInternal::CreateChannel: Created channel 53594e43
17:32:23.33   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:32:23.33   MessageInternal::DestroyChannel: Destroyed channel 474d4343
17:32:23.33   MessageInternal::DestroyChannel: Destroyed channel 474f424a
17:32:23.33   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
17:32:23.33   MessageInternal::DestroyChannel: Destroyed channel 53594e43
17:32:23.33   GAME -- Available memory: 3071MB Physical RAM, 3069MB Pagefile, 4095 Virtual Address Space
17:32:23.35   Transport - Largest received is now 19
17:32:23.35   Transport::OpenInternal request to WINaddr:127.0.0.1:6112;
17:32:23.36   Transport - Largest sent is now 19
17:32:26.29   DLLDriverLinker -- Adding driver 'spDx10.dll'.
17:32:26.30   DLLDriverLinker -- Adding driver 'spDx9.dll'.
17:32:26.30   DLLDriverLinker -- 2 DLL drivers found.
17:32:26.36   SPDx10 -- Adapter [ASUS EAH3450 Series]: 508MB dedicated video memory, 0MB dedicated system memory and 1279MB shared system memory.
17:32:28.05   DLLDriverLinker -- 2 DLL drivers found.
17:32:28.37   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
17:32:28.37   GAME -- Resolution set to 1024x768 (fullscreen).
17:32:28.38   SPDx9 -- Driver Name = atiumdag.dll  Desc = ASUS EAH3450 Series
17:32:28.38   SPDx9 -- Driver Vendor = 0x1002  Device = 0x95C5  SubSys = 0x01E21043  Rev = 0x0000
17:32:28.38   SPDx9 -- Driver Version  Product = 0x0007  Version = 0x000E  SubVersion = 0x00  Build = 5.97
17:32:28.38   SPDx9 -- Driver GUID = {D7B71EE2-D685-11CF-A671-E821A1C2CA35}
17:32:28.88   SPDx9 -- 1774MB available Texture Memory
17:32:28.88   ShaderDatabase: using shader profile [ps30]
17:32:29.41   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
17:32:29.91   GameObjLoader 0e166870 - resetting counters
17:32:29.91   GameObjLoader 0e166870 - Created loader
17:32:29.91   GameObjLoader 0e1669d0 - resetting counters
17:32:29.91   GameObjLoader 0e1669d0 - Created loader
17:32:30.20   GAME -- Beginning FE
17:32:30.20   Sent message game CompanyOfHeroes started 4924 602 allowtraffic
17:32:30.20   RemoteDLManager - Connection Restored.
17:32:30.20   UIFrontEnd - Loading Front End
17:32:30.20   THREAD: Hyper-Threading Technology Processors are not detected.
17:32:30.30   SOUND -- Initializing ...
17:32:30.38   INNIMapDCA Key not found: sp_speechducker::time
17:32:30.98   SOUND -- Initialization completed!
17:32:30.98   UIFrontEnd - Initializing Forms
17:32:32.80   CampaignFilter::BindFilterSpecificWidgets()
17:32:32.88   Activating screen: AppLoadingForm
17:32:32.88   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:32:32.88   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
17:32:34.58   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
17:32:34.58   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
17:32:34.59   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
17:32:34.59   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
17:32:34.59   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
17:32:34.59   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
17:32:35.03   GAME -- Using player profile NGOCQUANG-PC
17:32:38.20   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
17:32:39.12   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
17:32:40.95   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
17:32:40.95   RendezvousManager: CreateSession - starting profile=Guest login
17:32:41.00   GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure.
17:32:41.00   RendezvousManager - unable to connect to the server as guest
17:32:41.01   OnConnect: connect failure
17:32:41.01   RendezvousManager::Shutdown - deleting instance
17:32:41.01   RendezvousManager - terminating all server calls in progress
17:32:41.01   CallManager - terminating all server calls in progress (1 in progress)
17:32:41.01   RendezvousManager - destroying chat handler
17:32:41.01   ~RendezvousManager - m_state = 7, no need for server disconnect
17:32:41.03   OnConnect: this wasnt a reconnect, no need for autologin
17:32:41.05   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:32:41.05   CRC & Version Info : 0000025a:ce5073b5:ec3c9e0d eastern_front:602:factionfix.dll 1
17:32:41.05   Activating screen: FEMovie
17:32:41.05   Activating screen: OnlineWidget
17:32:41.05   Activating screen: RelicOnlineLogin
17:32:41.13   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:32:41.13   You have a valid version of the game.
17:32:41.15   AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now
17:32:41.15   installed_products = ( DLC1 DLC2 DLC3 )
17:32:41.15   OnLogin: no previous login, auto selecting profile not required
17:32:41.15   Activating screen: MessageBoxPopup
17:32:41.15   Activating screen: MessageBoxPopup
17:32:41.15   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
17:32:53.74   Beginning media verification...
17:32:53.74   Validator type : validators\udro\udro.dll
17:32:53.74   Activating screen: MessageBoxPopup
17:32:56.99   Activating screen: FE_mm_01
17:32:56.99   GAME -- Setting campaign state to 'dlc1'
17:32:56.99   GAME -- Closing state 'dlc1'
17:32:57.00   GAME -- Setting campaign state to 'dlc2'
17:32:57.00   GAME -- Closing state 'dlc2'
17:32:57.00   GAME -- Setting campaign state to 'dlc3'
17:32:57.00   GAME -- Closing state 'dlc3'
17:32:59.45   Activating screen: MessageBoxPopup
17:32:59.45   Created Matchinfo
17:32:59.45   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
17:32:59.45   starting LAN hosting
17:32:59.61   OnJoinAdvertisementSuccess - joined online match, server leave notification required
17:32:59.61   starting local hosting
17:32:59.61   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:32:59.61   Allocated route ID=0 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:32:59.61   Transport::OpenInternal request to WINaddr:5.162.102.231:6112;
17:32:59.61   Session::Host sid = FDAFAC930016053C, hostURL = , local addresses = WINaddr:5.162.102.231:6112;
17:32:59.61   ValidateCustomData: called with 217 bytes of custom data
17:32:59.61   Host accepted Peer 1 into the match at address list=WINaddr:5.162.102.231:6112;, routes=WINaddr:5.162.102.231:6112;
17:32:59.61   AdvertisementInternal::Process - EVENT_NEWPEER
17:32:59.61   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
17:32:59.63   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
17:32:59.63   hosting - Session is connected
17:32:59.63   Net::Session::SetVisible - session is set to VISIBLE.
17:32:59.63   hosting completed successfully
17:32:59.63   HostAsync - completed with HostResult = 0
17:32:59.63   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
17:32:59.63   Activating screen: OnlineGameSetup
17:32:59.63   MessageInternal::CreateChannel: Created channel 534d5347
17:32:59.63   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:5.162.102.231:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:32:59.63   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
17:32:59.64   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:32:59.64   MatchInternal::SetMatchType - new type 14 - updating server
17:32:59.64   Transport - Largest received is now 453
17:32:59.65   SetVisible called while !IsConnected
17:32:59.65   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:32:59.65   Activating screen: RelicOnlineChat
17:32:59.65   Activating screen: RelicOnlineNewsScreen
17:32:59.65   Activating screen: RelicOnlineStatsScreen
17:32:59.65   Activating screen: Achievements
17:32:59.66   GAME -- Setting campaign state to 'dlc1'
17:32:59.66   GAME -- Closing state 'dlc1'
17:32:59.66   GAME -- Setting campaign state to 'dlc1'
17:32:59.66   GAME -- Closing state 'dlc1'
17:32:59.66   GAME -- Setting campaign state to 'dlc2'
17:32:59.66   GAME -- Closing state 'dlc2'
17:32:59.66   Transport - Largest received is now 742
17:32:59.66   GAME -- Setting campaign state to 'dlc3'
17:32:59.66   GAME -- Closing state 'dlc3'
17:32:59.67   GAME -- Setting campaign state to 'dlc1'
17:32:59.67   GAME -- Closing state 'dlc1'
17:32:59.67   Activating screen: GameHistory
17:32:59.67   GAME -- Setting campaign state to 'dlc1'
17:32:59.68   GAME -- Closing state 'dlc1'
17:32:59.68   GAME -- Setting campaign state to 'dlc2'
17:32:59.68   GAME -- Closing state 'dlc2'
17:32:59.68   GAME -- Setting campaign state to 'dlc3'
17:32:59.68   GAME -- Closing state 'dlc3'
17:32:59.68   Transport - Largest received is now 1166
17:32:59.69   Activating screen: OnlineGameSetup
17:32:59.69   Activating screen: RelicOnlineTabs
17:32:59.69   AutomatchInternal::OnHostComplete - Completed Host with success=1
17:32:59.69   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
17:32:59.69   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
17:32:59.69   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:32:59.69   Net::Session::SetVisible - session is set to VISIBLE.
17:32:59.69   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:32:59.79   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:32:59.80   GAME -- Setting campaign state to 'dlc1'
17:32:59.80   GAME -- Closing state 'dlc1'
17:32:59.81   GAME -- Setting campaign state to 'dlc2'
17:32:59.81   GAME -- Closing state 'dlc2'
17:32:59.81   GAME -- Setting campaign state to 'dlc3'
17:32:59.81   GAME -- Closing state 'dlc3'
17:32:59.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:03.16   Activating screen: DynamicPopupMenu
17:33:03.32   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:03.32   MatchInternal::SetMatchType - new type 0 - updating server
17:33:03.32   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:03.32   Net::Session::SetVisible - session is set to VISIBLE.
17:33:03.32   Net::Session::SetVisible - session is set to VISIBLE.
17:33:03.32   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:03.33   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:03.34   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:03.34   Net::Session::SetVisible - session is set to VISIBLE.
17:33:03.34   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:03.36   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:03.39   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:04.28   Activating screen: DynamicPopupMenu
17:33:05.19   Activating screen: DynamicPopupMenu
17:33:05.38   OnlinePlayerListEntry::OnEnterSlot - Slot 4 selected...
17:33:05.40   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:05.40   Net::Session::SetVisible - session is set to VISIBLE.
17:33:05.40   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:05.43   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:05.46   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:06.59   Activating screen: RaceSelectionPopup
17:33:07.44   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:07.44   Net::Session::SetVisible - session is set to VISIBLE.
17:33:07.44   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:07.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:07.49   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:08.03   Activating screen: DynamicPopupMenu
17:33:08.83   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:08.83   Net::Session::SetVisible - session is set to VISIBLE.
17:33:08.83   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:08.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:08.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:09.54   Activating screen: DynamicPopupMenu
17:33:10.57   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:10.57   Net::Session::SetVisible - session is set to VISIBLE.
17:33:10.58   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:10.60   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:10.62   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:11.43   Activating screen: DynamicPopupMenu
17:33:12.04   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:12.04   Net::Session::SetVisible - session is set to VISIBLE.
17:33:12.04   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:12.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:12.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:12.49   Activating screen: DynamicPopupMenu
17:33:13.16   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:33:13.16   Net::Session::SetVisible - session is set to VISIBLE.
17:33:13.16   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:13.17   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:13.20   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:13.96   Activating screen: DynamicPopupMenu
17:33:14.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:14.81   MatchInternal::SetMatchType - new type 14 - updating server
17:33:14.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:14.81   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:14.81   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:14.81   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:14.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:14.84   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:14.84   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:14.84   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:14.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:14.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:15.68   Activating screen: RaceSelectionPopup
17:33:16.39   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:16.39   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:16.39   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:16.41   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:16.44   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:16.80   Activating screen: RaceSelectionPopup
17:33:17.36   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:17.36   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:17.36   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:17.38   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:17.41   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:18.02   Activating screen: RaceSelectionPopup
17:33:18.89   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:18.89   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:18.89   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:18.91   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:18.94   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:20.84   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:20.84   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:20.84   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:20.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:20.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:22.22   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:22.22   Net::Session::SetVisible - session is set to INVISIBLE.
17:33:22.22   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:33:22.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:22.26   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:24.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 1.9/0.0, #p/sec[s/r] = 0.3/0.8, max unsent 0, version err 0, merge 0
17:33:24.84   GameSetupForm - No win/loss reporting and only a single player, disconnecting
17:33:24.84   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:33:24.84   SetVisible called while !IsConnected
17:33:24.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:24.84   GameSetupForm - Starting game
17:33:24.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:24.85   PopulateGameInfo - random seed:[1312626804], guid:[{76cbff2a-d067-4e52-bc6d-eb5a8527a35e}], sync level:[2]
17:33:24.85   Error loading [DATA:levelingCurve.lua]
17:33:24.85   Error loading [DATA:levelingCurve.lua]
17:33:24.85   Error loading [DATA:levelingCurve.lua]
17:33:24.85   Error loading [DATA:levelingCurve.lua]
17:33:24.86   Error loading [DATA:levelingCurve.lua]
17:33:24.86   Error loading [DATA:levelingCurve.lua]
17:33:24.86   MOD - Setting player (0) race to: allies_soviets
17:33:24.86   MOD - Setting player (0) race to: 2
17:33:24.86   MOD - Setting player (1) race to: allies_soviets
17:33:24.86   MOD - Setting player (1) race to: 2
17:33:24.86   MOD - Setting player (2) race to: allies_soviets
17:33:24.86   MOD - Setting player (2) race to: 2
17:33:24.86   MOD - Setting player (3) race to: axis_panzer_elite
17:33:24.86   MOD - Setting player (3) race to: 4
17:33:24.86   MOD - Setting player (4) race to: axis
17:33:24.86   MOD - Setting player (4) race to: 3
17:33:24.86   MOD - Setting player (5) race to: axis
17:33:24.86   MOD - Setting player (5) race to: 3
17:33:24.86   Activating screen: MessageBoxPopup
17:33:24.86   Session is marking the local peer PeerID 1 for death
17:33:24.86   Session::GetEvent DestroyPeer success Peer 1 removed
17:33:24.86   Session::DestroyPeer - deallocating peer 1
17:33:24.86   Session - Requested Disconnect for connectionID 2
17:33:24.86   Deallocated route ID=3 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:33:24.86   Session - Requested Disconnect for connectionID 3
17:33:24.86   Closed route 3<>3 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:33:24.86   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:33:24.86   Destroyed Matchinfo
17:33:24.89   APP -- Game Start
17:33:24.89   Sent message game CompanyOfHeroes allowtraffic
17:33:24.89   GAME -- Setting campaign state to 'dlc1'
17:33:24.89   GAME -- Closing state 'dlc1'
17:33:24.89   GAME -- Setting campaign state to 'dlc2'
17:33:24.89   GAME -- Closing state 'dlc2'
17:33:24.89   GAME -- Setting campaign state to 'dlc3'
17:33:24.89   GAME -- Closing state 'dlc3'
17:33:24.90   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:33:24.90   GAME -- Ending FE
17:33:24.90   UIFrontEnd - Unloading Front End
17:33:24.92   SOUND -- Shutting down ...
17:33:24.97   SOUND -- Shutdown completed!
17:33:24.99
17:33:24.99   GAME -- *** Beginning mission 6p_kiev (1 Humans, 5 Computers) ***
17:33:24.99
17:33:25.17   GAME -- Recording game
17:33:25.24   Activating screen: GameLoadScreen
17:33:25.25   ReportGameStartStats - no connection to server unable to report
17:33:25.25   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:33:25.25   PeerRemoveAll - flushing local session peer data
17:33:25.25   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:33:25.25   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
17:33:25.61   THREAD: Hyper-Threading Technology Processors are not detected.
17:33:25.69   SOUND -- Initializing ...
17:33:26.42   SOUND -- Initialization completed!
17:33:26.46   PHYSICS: detected processor(s) capable of handling 4 threads.
17:33:26.50   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_kiev'
17:33:26.50   MOD -- Using Mod 'Eastern_Front'
17:33:26.50   Unable to load/parse precache file [DATA:scenarios\mp\6p_kiev_precache.lua]
17:33:26.60   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m04_shermans_arrive_low_load.smf'.  Expensive operation
17:33:26.61   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m04_shermans_arrive_low_load.smf'.  Expensive operation
17:33:30.23   GameObjLoader - upgrading load_count from 0 to 1102
17:33:34.14   GameObjLoader - upgrading load_count from 1102 to 1827
17:33:34.33   PHYSICS -- Created node factory 'HVOK'
17:33:34.33   PHYSICS -- Created node factory 'DMMY'
17:33:36.50   SPDx9 -- 1770MB available Texture Memory
17:33:47.74   GameObjLoader - upgrading load_count from 1455 to 4263
17:33:56.20   GameObjLoader 0e166870 - resetting counters
17:33:56.20   GameObjLoader 0e166870 - LOAD_DONE
17:33:56.25   GAME - SessionSetup
17:33:56.46   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
17:33:56.46   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
17:33:56.46   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
17:33:58.80   GAME - CreateGEWorld in 2555 ms
17:33:58.85   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
17:33:58.92   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m04_shermans_arrive_low_load.smf'.  Expensive operation
17:33:59.28   GAME - SessionSetup finished in 3036 ms
17:33:59.33   GAME - WaterReflectionManagerSetup
17:33:59.33   GAME - WaterReflectionManagerSetup finished in 0 ms
17:34:00.04   MessageInternal::CreateChannel: Created channel 474d4343
17:34:00.15   Regenerating ImpassMap data...
17:34:00.15       Impass Data was already valid, but regenerating...
17:34:00.46   Generating CanBuild Map.  THIS SHOULD ONLY HAPPEN IN WORLDBUILDER!  IF YOU SEE THIS IN GAME, RE-SAVE THE MAP!
17:34:00.46   Regenerating CanBuildMap data...
17:34:00.46   Generating CanShoot Map.
17:34:00.46   Pathfinder::Regenerate()...
17:34:00.56   Generating PathSectorMap...
17:34:01.24   Pathfinder::Regenerate() Done.
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x0658a008
[Cope FactionFix] - patched @0x71c35491
[Cope FactionFix] - hooked @0x71c35492
[Cope FactionFix] - patched @0x71c34a07
[Cope FactionFix] - hooked @0x71c34a07
[Cope FactionFix] - Hook installed
17:34:01.81   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded.
17:34:02.29   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:34:02.29   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:34:03.60   MessageInternal::CreateChannel: Created channel 4d4f444d
17:34:04.40   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:34:04.41   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:34:04.41   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:34:04.41   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:34:04.41   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:34:04.98   SPEECHMANAGER -- Loaded in 0.523496 seconds
17:34:09.11   SPDx9 -- 1759MB available Texture Memory
17:34:09.29   GameObjLoader - upgrading load_count from 0 to 403
17:34:10.98   GameObjLoader 0e1669d0 - resetting counters
17:34:10.98   GameObjLoader 0e1669d0 - LOAD_DONE
17:34:13.08   PreloadResources took 2030ms.
17:34:13.21   GAME -- Loading completed (48 seconds)
17:34:13.21   SIM -- Setting SyncErrorChecking level to None
17:34:25.01   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 1.9/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:34:27.72   Activating screen: GameScreen
17:34:27.72   Activating screen: Decorators
17:34:27.72   Activating screen: Taskbar
17:34:27.72   Activating screen: SubtitleScreen
17:34:27.72   Activating screen: TextOverlayScreen
17:34:27.88   PerformanceRecorder::StartRecording for game size 6
17:34:27.88   GAME -- Starting mission...
17:34:29.17   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:34:29.17   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:34:29.17   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI)
17:34:29.17   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI)
17:34:29.17   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:34:33.84   Warning: non auto-match upgrade not found in AE, tuning
17:34:40.07   Activating screen: pause_menu
17:34:40.09   GAME -- SimulationController::Pause 0
17:34:41.07   Activating screen: prompt_generic
17:34:41.82   MOD -- Player NGOCQUANG-PC (network abort) (frame 90) (KillPlayer)
17:34:41.82   GameApp::ReportStats
17:34:41.82   ReportStats - untracked game, no need to report match 4294967295:4294967295
17:34:41.82   ReportSimStats - nothing to report, no connection to the server
17:34:41.82   ReportStats - ReportSimStats failed
17:34:41.82   GameObj::DoGameOverPopup
17:34:41.82   Activating screen: GameOverDialog
17:34:41.82   GAME -- SimulationController::Pause 1
17:34:41.82   ArbitrationMessageNeeded 0 allies vs 0 enemies result 0
17:34:41.82   GameOverDialog - normal game ending (no sync errors)
17:34:43.05   Activating screen: ViewStats
17:34:44.52   Activating screen: postgame_stats_skirm
17:34:45.78   Activating screen: AppRestartingForm
17:34:45.78   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:34:45.78   Widget attempting to remove a non-existance listener. Widget:bkg_COH
17:34:45.78   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
17:34:45.78   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
17:34:45.78   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
17:34:45.78   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
17:34:45.80   APP -- Game Stop
17:34:45.80   GameApp::TransitionGameStop
17:34:45.80   GameApp::TransitionQuitCommon
17:34:45.80   PerformanceRecorder: Not enough game time to update profile history
17:34:45.80   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:45.80   PerformanceRecorder::EndRecording - game size=6, max average=0.000000, worst frame=0.057418
17:34:45.80    Recording: No [6 players]
17:34:45.80    Total render, sim time: 16.61, 16.96 sec
17:34:45.80    Total render, sim frames: 490, 336
17:34:45.80    Max/Avg: 0.00, 0.00 sec (fps=0.00, 0.00) (0 samples)
17:34:45.80    Bars: 0
17:34:45.80   Profile Max Performance[6 players] =  0.06 0.05 0.05 0.03 0.00
17:34:45.80   Profile Avg Performance[6 players] =  0.04 0.04 0.04 0.02 0.00
17:34:45.80   GameObj::ShutdownGameObj
17:34:45.80   GAME -- Ending mission (Game over)
17:34:45.90   GameObjLoader 0e1669d0 - Unload
17:34:45.90   GameObjLoader 0e1669d0 - resetting counters
17:34:45.94   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
17:34:45.94   MOD -- Game Over at frame 90
17:34:46.17   GameObjLoader 0e166870 - Unload
17:34:46.17   GameObjLoader 0e166870 - resetting counters
17:34:46.30   SOUND -- Shutting down ...
17:34:46.36   SOUND -- Shutdown completed!
17:34:46.36   MessageInternal::DestroyChannel: Destroyed channel 474d4343
17:34:46.36   GAME -- Beginning FE
17:34:46.36   Sent message game CompanyOfHeroes allowtraffic
17:34:46.36   UIFrontEnd - Loading Front End
17:34:46.36   THREAD: Hyper-Threading Technology Processors are not detected.
17:34:46.44   SOUND -- Initializing ...
17:34:46.49   SOUND -- Initialization completed!
17:34:46.49   UIFrontEnd - Initializing Forms
17:34:48.41   CampaignFilter::BindFilterSpecificWidgets()
17:34:48.47   Turning off auto-selection of profiles, we already have a profile selected.
17:34:48.48   MOD -- Mounting EF_DLC1, 1.0
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC1\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC1\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC1\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC1\Movies\'
17:34:48.48   MOD -- Mounting EF_DLC2, 1.0
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC2\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC2\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC2\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC2\Movies\'
17:34:48.48   MOD -- Mounting EF_DLC3, 1.0
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC3\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC3\DataArtHigh\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC3\DataSoundEnglish\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\DLC3\Movies\'
17:34:48.48   MOD -- Mounting EF_OPS, 1.0
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
17:34:48.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\Ops\Data\'
17:34:48.49   MOD -- Mounting Eastern_Front, 1.0
17:34:48.49   FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\'
17:34:48.49   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\RelicOnline\Data\'
17:34:48.49   FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\'
17:34:48.49   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\Engine\Data\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\Data\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\Data\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtHigh\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\DataArtHigh\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\DataSoundHigh\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\DataSound\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\'
17:34:48.50   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\'
17:34:48.51   FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\'
17:34:48.51   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Engine\Locale\English\Data\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\DataSoundEnglish\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\CoH\Locale\English\Data\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\DataAttrib\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Eastern_Front\Movies\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\Engine\Movies\'
17:34:48.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Ngoc.Quang\Documents\My Games\Company of Heroes\WW2\Movies\'
17:34:48.56   Activating screen: AppRestartingForm
17:34:48.56   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:34:48.56   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
17:34:49.43   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:34:49.44   CRC & Version Info : 0000025a:ce5073b5:ec3c9e0d eastern_front:602:factionfix.dll 1
17:34:49.44   Activating screen: FEMovie
17:34:49.44   Activating screen: OnlineWidget
17:34:49.44   Activating screen: FE_mm_01
17:34:49.49   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:34:49.49   GAME -- Setting campaign state to 'dlc1'
17:34:49.49   GAME -- Closing state 'dlc1'
17:34:49.49   GAME -- Setting campaign state to 'dlc2'
17:34:49.49   GAME -- Closing state 'dlc2'
17:34:49.49   GAME -- Setting campaign state to 'dlc3'
17:34:49.49   GAME -- Closing state 'dlc3'
17:34:51.91   Activating screen: MessageBoxPopup
17:34:51.91   Created Matchinfo
17:34:51.91   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
17:34:51.91   starting LAN hosting
17:34:51.94   OnJoinAdvertisementSuccess - joined online match, server leave notification required
17:34:51.94   starting local hosting
17:34:51.94   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:34:51.94   Allocated route ID=0 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:34:51.94   Transport::OpenInternal request to WINaddr:5.162.102.231:6112;
17:34:51.94   Session::Host sid = 9459B3C1FDD0F2AE, hostURL = , local addresses = WINaddr:5.162.102.231:6112;
17:34:51.94   ValidateCustomData: called with 217 bytes of custom data
17:34:51.94   Host accepted Peer 1 into the match at address list=WINaddr:5.162.102.231:6112;, routes=WINaddr:5.162.102.231:6112;
17:34:51.94   AdvertisementInternal::Process - EVENT_NEWPEER
17:34:51.94   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
17:34:51.95   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
17:34:51.95   hosting - Session is connected
17:34:51.95   Net::Session::SetVisible - session is set to VISIBLE.
17:34:51.97   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:5.162.102.231:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:34:51.97   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
17:34:51.97   hosting completed successfully
17:34:51.97   HostAsync - completed with HostResult = 0
17:34:51.97   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
17:34:51.97   Activating screen: OnlineGameSetup
17:34:51.97   MessageInternal::CreateChannel: Created channel 534d5347
17:34:51.97   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:34:51.97   MatchInternal::SetMatchType - new type 14 - updating server
17:34:51.99   SetVisible called while !IsConnected
17:34:51.99   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:51.99   Activating screen: RelicOnlineChat
17:34:51.99   Activating screen: RelicOnlineNewsScreen
17:34:51.99   Activating screen: RelicOnlineStatsScreen
17:34:51.99   Activating screen: Achievements
17:34:51.99   GAME -- Setting campaign state to 'dlc1'
17:34:51.99   GAME -- Closing state 'dlc1'
17:34:52.00   GAME -- Setting campaign state to 'dlc2'
17:34:52.00   GAME -- Closing state 'dlc2'
17:34:52.00   GAME -- Setting campaign state to 'dlc3'
17:34:52.00   GAME -- Closing state 'dlc3'
17:34:52.00   GAME -- Setting campaign state to 'dlc1'
17:34:52.00   GAME -- Closing state 'dlc1'
17:34:52.01   Activating screen: GameHistory
17:34:52.01   GAME -- Setting campaign state to 'dlc1'
17:34:52.04   GAME -- Closing state 'dlc1'
17:34:52.04   GAME -- Setting campaign state to 'dlc2'
17:34:52.04   GAME -- Closing state 'dlc2'
17:34:52.04   GAME -- Setting campaign state to 'dlc3'
17:34:52.04   GAME -- Closing state 'dlc3'
17:34:52.05   Activating screen: OnlineGameSetup
17:34:52.05   Activating screen: RelicOnlineTabs
17:34:52.05   AutomatchInternal::OnHostComplete - Completed Host with success=1
17:34:52.05   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
17:34:52.05   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
17:34:52.05   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:34:52.05   Net::Session::SetVisible - session is set to VISIBLE.
17:34:52.06   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:52.11   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:52.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:53.61   Activating screen: DynamicPopupMenu
17:34:55.37   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:55.37   MatchInternal::SetMatchType - new type 0 - updating server
17:34:55.37   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:55.37   Net::Session::SetVisible - session is set to VISIBLE.
17:34:55.37   Net::Session::SetVisible - session is set to VISIBLE.
17:34:55.37   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:55.38   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:55.40   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:55.40   Net::Session::SetVisible - session is set to VISIBLE.
17:34:55.40   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:55.42   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:55.45   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:56.39   Activating screen: RaceSelectionPopup
17:34:56.93   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:56.93   Net::Session::SetVisible - session is set to VISIBLE.
17:34:56.93   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:56.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:56.97   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:57.38   Activating screen: DynamicPopupMenu
17:34:57.94   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:57.94   Net::Session::SetVisible - session is set to VISIBLE.
17:34:57.94   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:57.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:57.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:58.52   Activating screen: DynamicPopupMenu
17:34:59.11   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:34:59.11   Net::Session::SetVisible - session is set to VISIBLE.
17:34:59.11   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:34:59.12   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:59.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:34:59.86   Activating screen: DynamicPopupMenu
17:35:00.60   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:35:00.60   Net::Session::SetVisible - session is set to VISIBLE.
17:35:00.60   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:00.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:00.64   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:01.01   Activating screen: DynamicPopupMenu
17:35:01.77   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:35:01.77   Net::Session::SetVisible - session is set to VISIBLE.
17:35:01.77   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:01.79   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:01.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:02.41   Activating screen: DynamicPopupMenu
17:35:02.96   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:02.96   MatchInternal::SetMatchType - new type 14 - updating server
17:35:02.96   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:02.96   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:02.96   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:02.96   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:02.97   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:02.99   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:02.99   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:02.99   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:03.01   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:03.04   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:03.77   Activating screen: DynamicPopupMenu
17:35:04.36   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:04.36   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:04.36   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:04.37   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:04.41   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:05.12   Activating screen: RaceSelectionPopup
17:35:05.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:05.80   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:05.80   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:05.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:05.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:06.26   Activating screen: RaceSelectionPopup
17:35:06.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:06.81   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:06.81   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:06.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:06.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:07.22   Activating screen: RaceSelectionPopup
17:35:07.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:07.80   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:07.80   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:07.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:07.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:08.44   Activating screen: RaceSelectionPopup
17:35:12.38   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:12.38   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:12.39   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:12.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:12.43   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:13.76   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:13.76   Net::Session::SetVisible - session is set to INVISIBLE.
17:35:13.76   GetMaxFrameTimeFromProfile: players=6 expected FPS=17.416018, bars=5, max avg=0.048, sd=0.009, 4 samples =  0.06 0.05 0.05 0.03
17:35:13.77   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:13.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:15.93   GameSetupForm - No win/loss reporting and only a single player, disconnecting
17:35:15.93   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:35:15.93   SetVisible called while !IsConnected
17:35:15.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:15.93   GameSetupForm - Starting game
17:35:15.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:35:15.94   PopulateGameInfo - random seed:[1312626915], guid:[{845f1e62-8778-452c-b857-4d1813694a1e}], sync level:[2]
17:35:15.94   Error loading [DATA:levelingCurve.lua]
17:35:15.94   Error loading [DATA:levelingCurve.lua]
17:35:15.94   Error loading [DATA:levelingCurve.lua]
17:35:15.94   Error loading [DATA:levelingCurve.lua]
17:35:15.95   Error loading [DATA:levelingCurve.lua]
17:35:15.95   Error loading [DATA:levelingCurve.lua]
17:35:15.95   MOD - Setting player (0) race to: allies_soviets
17:35:15.95   MOD - Setting player (0) race to: 2
17:35:15.95   MOD - Setting player (1) race to: allies_soviets
17:35:15.95   MOD - Setting player (1) race to: 2
17:35:15.95   MOD - Setting player (2) race to: allies_soviets
17:35:15.95   MOD - Setting player (2) race to: 2
17:35:15.95   MOD - Setting player (3) race to: axis_panzer_elite
17:35:15.95   MOD - Setting player (3) race to: 4
17:35:15.95   MOD - Setting player (4) race to: axis
17:35:15.95   MOD - Setting player (4) race to: 3
17:35:15.95   MOD - Setting player (5) race to: axis
17:35:15.95   MOD - Setting player (5) race to: 3
17:35:15.95   Activating screen: MessageBoxPopup
17:35:15.95   Session is marking the local peer PeerID 1 for death
17:35:15.95   Session::GetEvent DestroyPeer success Peer 1 removed
17:35:15.95   Session::DestroyPeer - deallocating peer 1
17:35:15.95   Session - Requested Disconnect for connectionID 4
17:35:15.95   Deallocated route ID=5 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:35:15.95   Session - Requested Disconnect for connectionID 5
17:35:15.95   Closed route 5<>5 for PeerID 1 at WINaddr:5.162.102.231:6112;
17:35:15.95   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:35:15.95   Destroyed Matchinfo
17:35:15.96   APP -- Game Start
17:35:15.96   Sent message game CompanyOfHeroes allowtraffic
17:35:15.96   GAME -- Setting campaign state to 'dlc1'
17:35:15.96   GAME -- Closing state 'dlc1'
17:35:15.96   GAME -- Setting campaign state to 'dlc2'
17:35:15.96   GAME -- Closing state 'dlc2'
17:35:15.96   GAME -- Setting campaign state to 'dlc3'
17:35:15.96   GAME -- Closing state 'dlc3'
17:35:15.97   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:35:15.99   GAME -- Ending FE
17:35:15.99   UIFrontEnd - Unloading Front End
17:35:16.00   SOUND -- Shutting down ...
17:35:16.08   SOUND -- Shutdown completed!
17:35:16.09
17:35:16.09   GAME -- *** Beginning mission 6p_kiev (1 Humans, 5 Computers) ***
17:35:16.09
17:35:16.23   GAME -- Recording game
17:35:16.30   Activating screen: GameLoadScreen
17:35:16.30   ReportGameStartStats - no connection to server unable to report
17:35:16.30   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:35:16.30   PeerRemoveAll - flushing local session peer data
17:35:16.30   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:35:16.30   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
17:35:16.55   THREAD: Hyper-Threading Technology Processors are not detected.
17:35:16.63   SOUND -- Initializing ...
17:35:17.37   SOUND -- Initialization completed!
17:35:17.39   PHYSICS: detected processor(s) capable of handling 4 threads.
17:35:17.39   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_kiev'
17:35:17.39   MOD -- Using Mod 'Eastern_Front'
17:35:17.42   Unable to load/parse precache file [DATA:scenarios\mp\6p_kiev_precache.lua]
17:35:17.54   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_force_beyond_reckoning_lower_load.smf'.  Expensive operation
17:35:17.55   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_force_beyond_reckoning_lower_load.smf'.  Expensive operation
17:35:20.96   GameObjLoader - upgrading load_count from 0 to 1087
17:35:23.53   GameObjLoader - upgrading load_count from 1087 to 1822
17:35:26.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.7, max unsent 0, version err 0, merge 0
17:35:30.34   GameObjLoader - upgrading load_count from 1440 to 4438
17:35:35.25   GameObjLoader 0e166870 - resetting counters
17:35:35.25   GameObjLoader 0e166870 - LOAD_DONE
17:35:35.25   GAME - SessionSetup
17:35:35.33   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
17:35:35.33   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
17:35:35.33   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
17:35:37.29   GAME - CreateGEWorld in 2040 ms
17:35:37.36   GAME - SessionSetup finished in 2110 ms
17:35:37.36   GAME - WaterReflectionManagerSetup
17:35:37.36   GAME - WaterReflectionManagerSetup finished in 0 ms
17:35:37.90   MessageInternal::CreateChannel: Created channel 474d4343
17:35:37.91   Regenerating ImpassMap data...
17:35:37.91       Impass Data was already valid, but regenerating...
17:35:38.23   Generating CanBuild Map.  THIS SHOULD ONLY HAPPEN IN WORLDBUILDER!  IF YOU SEE THIS IN GAME, RE-SAVE THE MAP!
17:35:38.23   Regenerating CanBuildMap data...
17:35:38.23   Generating CanShoot Map.
17:35:38.23   Pathfinder::Regenerate()...
17:35:38.33   Generating PathSectorMap...
17:35:39.01   Pathfinder::Regenerate() Done.
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x06566110
[Cope FactionFix] - Hook already installed
17:35:39.17   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded.
17:35:39.56   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:35:39.56   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:35:40.41   MessageInternal::CreateChannel: Created channel 4d4f444d
17:35:41.15   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:35:41.16   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:35:41.16   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:35:41.16   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:35:41.16   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:35:41.71   SPEECHMANAGER -- Loaded in 0.507722 seconds
17:35:43.12   GameObjLoader - upgrading load_count from 0 to 305
17:35:44.04   GameObjLoader 0e1669d0 - resetting counters
17:35:44.04   GameObjLoader 0e1669d0 - LOAD_DONE
17:35:46.10   PreloadResources took 1990ms.
17:35:46.29   GAME -- Loading completed (30 seconds)
17:35:46.29   SIM -- Setting SyncErrorChecking level to None
17:35:57.41   Activating screen: GameScreen
17:35:57.41   Activating screen: Decorators
17:35:57.41   Activating screen: Taskbar
17:35:57.41   Activating screen: SubtitleScreen
17:35:57.41   Activating screen: TextOverlayScreen
17:35:57.53   PerformanceRecorder::StartRecording for game size 6
17:35:57.53   GAME -- Starting mission...
17:35:58.46   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:35:58.46   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:35:58.46   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI)
17:35:58.46   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
17:35:58.46   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI)
17:36:03.20   Warning: non auto-match upgrade not found in AE, tuning
17:36:04.75   Activating screen: Command_Tree
17:36:11.40   Activating screen: Command_Branch
17:36:14.02   Activating screen: Command_Branch
17:36:19.39   Activating screen: Command_Branch
17:36:27.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:36:28.14   Activating screen: Command_Branch
17:36:33.78   Activating screen: Command_Branch
17:36:36.33   Activating screen: Command_Branch
17:36:43.13   Activating screen: Command_Branch
17:36:48.53   Warning: binding repeat_1(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
17:36:48.56   Warning: binding repeat_3(Ability: abilities\repair_ability_pe_improved) -- widget 'command_ability_button_06' already occupied by 'repeat_2:Ability: abilities\repair_ability_pe'
17:37:28.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:38:29.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:38:34.49   Warning: binding repeat_2(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
17:38:34.49   Warning: binding repeat_4(Ability: abilities\repair_ability_pe_improved) -- widget 'command_ability_button_06' already occupied by 'repeat_3:Ability: abilities\repair_ability_pe'
17:38:48.42   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
17:39:30.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:40:01.62   Warning: binding repeat_3(Production Item: sbps\races\axis_panzer_elite\vehicles\250_light_halftrack_squad_with_tankbuster) -- widget 'command_button_01' already occupied by 'repeat_0:Production Item: sbps\races\axis_panzer_elite\vehicles\250_light_halftrack_squad'
17:40:31.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:41:14.97   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:14.97   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:14.97   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.
17:41:16.25   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:16.25   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:16.25   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.
17:41:16.25   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:16.25   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:16.25   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.
17:41:19.04   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
17:41:19.04   Muting spew of bind updater.  Fix art and re-test.
17:41:30.84   Activating screen: Command_Branch
17:41:32.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:42:33.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:43:34.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:43:47.73   Was already stealing a skeleton when told to steal another [mortar_target].
17:44:35.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:45:36.00   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:46:37.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:47:33.15   Unabled to do upgrade, CANPRODUCE_NoResources
17:47:34.38   Unabled to do upgrade, CANPRODUCE_NoResources
17:47:35.51   Unabled to do upgrade, CANPRODUCE_NoResources
17:47:38.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:48:00.14   Warning: upgrade in upgrade prerequisite doesn't exist
17:48:00.25   Warning: upgrade in upgrade prerequisite doesn't exist
17:48:09.56   Activating screen: Command_Branch
17:48:39.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:48:55.46   Unabled to do upgrade, CANPRODUCE_NoResources
17:48:56.59   Unabled to do upgrade, CANPRODUCE_NoResources
17:49:01.89   Unabled to do upgrade, CANPRODUCE_NoResources
17:49:03.03   Unabled to do upgrade, CANPRODUCE_NoResources
17:49:40.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:50:41.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:50:52.49   Activating screen: Command_Branch
17:51:42.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:52:14.27   Unabled to do upgrade, CANPRODUCE_NoResources
17:52:15.27   Unabled to do upgrade, CANPRODUCE_NoResources
17:52:16.40   Unabled to do upgrade, CANPRODUCE_NoResources
17:52:43.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:53:44.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:54:30.41   Activating screen: Command_Branch
17:54:45.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:55:46.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:56:47.00   Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.6, max unsent 0, version err 0, merge 0
17:56:59.33   Unabled to do upgrade, CANPRODUCE_NoResources
17:57:00.47   Unabled to do upgrade, CANPRODUCE_NoResources
17:57:48.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:58:18.41   Activating screen: Command_Branch
17:58:49.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:59:25.85   FXShader::SetVariable( "tex0", T ) uses unknown variable.
17:59:41.47   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:42.46   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:43.60   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:44.72   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:45.86   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:46.84   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:47.96   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:48.97   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:50.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.3/0.5, max unsent 0, version err 0, merge 0
17:59:50.39   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:51.47   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:52.58   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:53.72   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:54.83   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:55.85   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:56.86   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:57.85   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:58.84   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
17:59:59.84   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:00.98   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:01.99   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:03.11   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:04.22   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:05.34   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:06.46   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:07.59   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:08.58   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:09.85   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:10.85   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:11.85   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:13.08   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:14.08   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:15.15   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:16.38   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:17.35   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:18.46   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:19.47   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:20.46   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:21.59   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:22.58   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:23.59   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:24.58   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:25.60   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:26.71   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:27.86   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:28.84   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:29.83   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:30.84   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:31.96   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
18:00:32.96   An ability failed to cast because target validation failed: abilities\soviets_air_all_targets
SIM -- Attempting to query bag not associated with this group type.
Group is of type 8.  Query is for type 1.  Group name: 'sbps\races\allies_soviets\soldiers\soviet_stsappers_squad'
-- FATAL EXIT --
simengine/propertysystem/propertybaggroup.h/85:!
--stack trace--

0x749924CD: LuaBinding::Obj::Dispatcher                 (D:\Coh Tov\LuaConfig.dll)
0x7497FA30: lua_yield                                   (D:\Coh Tov\LuaConfig.dll)
0x74981AFB: lua_resume                                  (D:\Coh Tov\LuaConfig.dll)
0x7497FA9E: lua_yield                                   (D:\Coh Tov\LuaConfig.dll)
0x7497FBD7: lua_resume                                  (D:\Coh Tov\LuaConfig.dll)
0x74977FBE: lua_pcall                                   (D:\Coh Tov\LuaConfig.dll)
0x7498D906: luax_callalert                              (D:\Coh Tov\LuaConfig.dll)
0x7444786C: MarkerObj::IsPersistent                     (D:\Coh Tov\SimEngine.dll)
