-- Log file for all dbTracef messages -- 16:52:18.47 NetworkManager::Create - creating network manager 16:52:18.47 Read 292 bytes from network datastore login_cache.bin 16:52:18.47 Read 52 bytes from network datastore global_cache.bin 16:52:18.47 Read 28 bytes from network datastore ping_cache.bin 16:52:18.47 QuazalInitializer - static initializing Quazal library 16:52:18.47 PingCache - populating cache with 0 pings 16:52:18.47 Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header 16:52:18.47 WinTransport - CreateSocket exclusive broadcast socket was available. 16:52:18.47 WinTransport - CreateSocket listening for broadcasts on default port 16:52:18.47 WinTransport - Host Name: KMartis-PC, aliases: , type=AF_INET, len=4 16:52:18.47 WinTransport - Host IP Address #0: [IP address redacted] 16:52:18.47 WinTransport - Host IP Address #1: [IP address redacted] 16:52:18.47 WinTransport - WARNING, MultipleNetwork adapters may cause problems 16:52:18.48 WinTransport - Interface #0: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_MULTICAST 16:52:18.48 WinTransport - Interface #1: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_MULTICAST 16:52:18.48 WinTransport - Interface #2: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST 16:52:18.48 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 16:52:18.48 WinTransport - Quazal address string = udp:/address=[IP address redacted];port=6112 16:52:18.48 SessionManager - Peer Header Size = 16 bytes 16:52:18.48 SessionManager - Game Data overhead = 7 bytes 16:52:18.48 SessionManager - Proxy overhead = 7 bytes 16:52:18.48 MessageInternal::CreateChannel: Created channel 47535450 16:52:18.48 Session::Initialize - info, initializing session object, using threads. 16:52:18.48 SessionManager::RegisterSession - Registering new session 0868cf00 16:52:18.48 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 16:52:18.48 AutomatchInternal: Instantiating 16:52:18.48 PartyInternal: Instantiating 16:52:18.48 MessageInternal::CreateChannel: Created channel 50525459 16:52:18.48 MessageInternal::CreateChannel: Created channel 51434b4d 16:52:18.48 Net::ThreadFunction - Entering network thread function... 16:52:18.48 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 16:52:18.48 MessageInternal::CreateChannel: Created channel 534d5347 16:52:18.48 MessageInternal::CreateChannel: Created channel 474d4343 16:52:18.48 MessageInternal::CreateChannel: Created channel 474f424a 16:52:18.48 MessageInternal::CreateChannel: Created channel 4d4f444d 16:52:18.48 MessageInternal::CreateChannel: Created channel 53594e43 16:52:18.48 MessageInternal::DestroyChannel: Destroyed channel 534d5347 16:52:18.48 MessageInternal::DestroyChannel: Destroyed channel 474d4343 16:52:18.48 MessageInternal::DestroyChannel: Destroyed channel 474f424a 16:52:18.48 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 16:52:18.48 MessageInternal::DestroyChannel: Destroyed channel 53594e43 16:52:18.48 GAME -- Available memory: 16303MB Physical RAM, 16301MB Pagefile, 4095 Virtual Address Space 16:52:18.50 Transport - Largest received is now 19 16:52:18.50 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 16:52:19.15 DLLDriverLinker -- Adding driver 'spDx10.dll'. 16:52:19.16 DLLDriverLinker -- Adding driver 'spDx9.dll'. 16:52:19.16 DLLDriverLinker -- 2 DLL drivers found. 16:52:19.59 SPDx10 -- Adapter [NVIDIA GeForce GTX 1080]: 3072MB dedicated video memory, 0MB dedicated system memory and 1023MB shared system memory. 16:52:21.60 DLLDriverLinker -- 2 DLL drivers found. 16:52:21.64 SPOOGE - Driver[DirectX9 rendering device] version[4,36] 16:52:21.64 GAME -- Resolution set to 3840x2160 (windowed). 16:52:21.65 SPDx9 -- Driver Name = nvd3dum.dll Desc = NVIDIA GeForce GTX 1080 16:52:21.65 SPDx9 -- Driver Vendor = 0x10DE Device = 0x1B80 SubSys = 0x62863842 Rev = 0x00A1 16:52:21.65 SPDx9 -- Driver Version Product = 0x0015 Version = 0x0015 SubVersion = 0x00 Build = 378.92 16:52:21.65 SPDx9 -- Driver GUID = {D7B71E3E-58C0-11CF-2645-8B421BC2D835} 16:52:22.11 SPDx9 -- 3624MB available Texture Memory 16:52:22.11 ShaderDatabase: using shader profile [ps15] 16:52:22.37 FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua' 16:52:22.44 GameObjLoader 083c6528 - resetting counters 16:52:22.44 GameObjLoader 083c6528 - Created loader 16:52:22.44 GameObjLoader 083c6688 - resetting counters 16:52:22.44 GameObjLoader 083c6688 - Created loader 16:52:22.55 GAME -- Beginning FE 16:52:22.55 Sent message game CompanyOfHeroes started 2356 602 allowtraffic 16:52:22.55 RemoteDLManager - Connection Restored. 16:52:22.55 UIFrontEnd - Loading Front End 16:52:22.55 THREAD: Hyper-Threading Technology Processors are not detected. 16:52:22.65 SOUND -- Initializing ... 16:52:22.68 INNIMapDCA Key not found: sp_speechducker::time 16:52:22.91 SOUND -- Initialization completed! 16:52:22.91 UIFrontEnd - Initializing Forms 16:52:23.67 CampaignFilter::BindFilterSpecificWidgets() 16:52:23.72 Activating screen: AppLoadingForm 16:52:23.72 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 16:52:23.72 Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3] 16:52:24.05 GAME -- (worldmanager) Cannot find original scenario "4p_vyazma_challenge" referenced from data:scenarios\mp\challenge_vyazma.scenref 16:52:24.05 GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh] 16:52:24.05 GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1] 16:52:24.05 GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2] 16:52:24.05 GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3] 16:52:24.05 GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2] 16:52:24.05 GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1] 16:52:24.21 GAME -- Using player profile KMARTIS-PC 16:52:24.25 Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase. 16:52:24.29 Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase. 16:52:24.50 QuazalLoginService - *** Connecting to server: localhost:30260 16:52:24.50 RendezvousManager: CreateSession - starting profile=Guest login 16:52:39.51 GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure. 16:52:39.51 RendezvousManager - unable to connect to the server as guest 16:52:39.51 OnConnect: connect failure 16:52:39.51 RendezvousManager::Shutdown - deleting instance 16:52:39.51 RendezvousManager - terminating all server calls in progress 16:52:39.51 CallManager - terminating all server calls in progress (1 in progress) 16:52:39.51 RendezvousManager - destroying chat handler 16:52:39.51 ~RendezvousManager - m_state = 7, no need for server disconnect 16:52:39.53 OnConnect: this wasnt a reconnect, no need for autologin 16:52:39.53 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 16:52:39.53 CRC & Version Info : 0000025a:703d9745:ec3c9e0d eastern_front:602:factionfix.dll 1 16:52:39.53 Activating screen: FEMovie 16:52:39.53 Activating screen: OnlineWidget 16:52:39.53 Activating screen: RelicOnlineLogin 16:52:39.56 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 16:52:39.56 You have a valid version of the game. 16:52:39.56 AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now 16:52:39.56 installed_products = ( COH CXP1 DLC1 DLC2 DLC3 DLC4 DLC5 DLC6 ) 16:52:39.56 OnLogin: no previous login, auto selecting profile not required 16:52:39.56 Beginning media verification... 16:52:39.56 Validator type : validators\gdon\gdon.dll 16:52:39.56 Activating screen: MessageBoxPopup 16:52:39.56 Activating screen: MessageBoxPopup 16:52:39.56 ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1 16:52:39.58 Activating screen: FE_mm_01 16:52:39.58 GAME -- Setting campaign state to 'coh' 16:52:39.58 GAME -- Closing state 'coh' 16:52:39.58 GAME -- Setting campaign state to 'cxp2' 16:52:39.58 GAME -- Closing state 'cxp2' 16:52:39.58 GAME -- Setting campaign state to 'cxp1' 16:52:39.58 GAME -- Closing state 'cxp1' 16:52:39.58 GAME -- Setting campaign state to 'dlc1' 16:52:39.58 GAME -- Closing state 'dlc1' 16:52:39.58 GAME -- Setting campaign state to 'dlc2' 16:52:39.58 GAME -- Closing state 'dlc2' 16:52:39.58 GAME -- Setting campaign state to 'dlc3' 16:52:39.58 GAME -- Closing state 'dlc3' 16:52:42.84 UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01 16:52:42.84 Activating screen: OnlineSelectGame 16:52:42.84 Activating screen: RelicOnlineChat 16:52:42.84 Activating screen: RelicOnlineNewsScreen 16:52:42.85 Activating screen: RelicOnlineStatsScreen 16:52:42.85 Activating screen: Achievements 16:52:42.85 GAME -- Setting campaign state to 'dlc1' 16:52:42.85 GAME -- Closing state 'dlc1' 16:52:42.85 GAME -- Setting campaign state to 'coh' 16:52:42.85 GAME -- Closing state 'coh' 16:52:42.85 GAME -- Setting campaign state to 'cxp2' 16:52:42.85 GAME -- Closing state 'cxp2' 16:52:42.85 GAME -- Setting campaign state to 'cxp1' 16:52:42.85 GAME -- Closing state 'cxp1' 16:52:42.85 GAME -- Setting campaign state to 'dlc1' 16:52:42.85 GAME -- Closing state 'dlc1' 16:52:42.85 GAME -- Setting campaign state to 'dlc2' 16:52:42.85 GAME -- Closing state 'dlc2' 16:52:42.85 GAME -- Setting campaign state to 'dlc3' 16:52:42.85 GAME -- Closing state 'dlc3' 16:52:42.85 GAME -- Setting campaign state to 'dlc1' 16:52:42.85 GAME -- Closing state 'dlc1' 16:52:42.85 Activating screen: GameHistory 16:52:42.85 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:42.85 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:42.85 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:42.86 Activating screen: OnlineSelectGame 16:52:42.86 Activating screen: RelicOnlineTabs 16:52:42.86 Activating screen: ScreenTipPopup 16:52:42.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:42.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:42.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:45.99 Activating screen: OnlineGameCreate 16:52:45.99 Activating screen: RelicOnlineTabs 16:52:48.28 Activating screen: MessageBoxPopup 16:52:48.28 Created Matchinfo 16:52:48.28 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 16:52:48.29 starting LAN hosting 16:52:48.29 OnJoinAdvertisementSuccess - joined online match, server leave notification required 16:52:48.29 starting local hosting 16:52:48.29 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 16:52:48.29 Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112; 16:52:48.29 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 16:52:48.29 Session::Host sid = B852336CA033935F, hostURL = , local addresses = WINaddr:[IP address redacted]:6112; 16:52:48.29 ValidateCustomData: called with 205 bytes of custom data 16:52:48.29 Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112; 16:52:48.29 AdvertisementInternal::Process - EVENT_NEWPEER 16:52:48.29 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 16:52:48.30 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 16:52:48.30 hosting - Session is connected 16:52:48.30 Net::Session::SetVisible - session is set to VISIBLE. 16:52:48.30 hosting completed successfully 16:52:48.30 HostAsync - completed with HostResult = 0 16:52:48.30 Activating screen: OnlineGameSetup 16:52:48.30 Activating screen: RelicOnlineTabs 16:52:48.30 AutomatchInternal::OnHostComplete - Completed Host with success=1 16:52:48.30 AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring 16:52:48.30 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 16:52:48.30 MessageInternal::CreateChannel: Created channel 534d5347 16:52:48.30 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:52:48.31 Net::Session::SetVisible - session is set to VISIBLE. 16:52:48.31 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:52:48.31 ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1 16:52:48.31 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:48.32 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 16:52:48.32 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 16:52:48.32 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:52:48.32 Net::Session::SetVisible - session is set to VISIBLE. 16:52:48.32 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:52:48.33 Transport - Largest received is now 1060 16:52:48.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:48.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:52.68 Activating screen: DynamicPopupMenu 16:52:52.84 OnlinePlayerListEntry::OnEnterSlot - Slot 4 selected... 16:52:52.85 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:52:52.85 Net::Session::SetVisible - session is set to VISIBLE. 16:52:52.85 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:52:52.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:52.87 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:57.39 Activating screen: DynamicPopupMenu 16:52:58.07 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:52:58.07 Net::Session::SetVisible - session is set to VISIBLE. 16:52:58.07 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:52:58.08 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:52:58.09 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:02.55 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:02.55 Net::Session::SetVisible - session is set to VISIBLE. 16:53:02.55 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:02.55 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:02.56 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:03.94 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:03.94 Net::Session::SetVisible - session is set to VISIBLE. 16:53:03.94 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:03.96 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:03.97 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:05.65 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:05.65 Net::Session::SetVisible - session is set to VISIBLE. 16:53:05.65 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:05.66 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:05.66 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:05.66 Net::Session::SetVisible - session is set to VISIBLE. 16:53:05.66 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:05.67 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:05.68 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:08.07 Activating screen: DynamicPopupMenu 16:53:09.71 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:09.71 Net::Session::SetVisible - session is set to VISIBLE. 16:53:09.71 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:09.71 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:09.72 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:10.20 Activating screen: DynamicPopupMenu 16:53:11.07 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:11.07 Net::Session::SetVisible - session is set to VISIBLE. 16:53:11.07 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:11.08 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:11.09 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:14.94 Activating screen: DynamicPopupMenu 16:53:15.93 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 16:53:15.93 Net::Session::SetVisible - session is set to VISIBLE. 16:53:15.93 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:15.94 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:15.95 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:17.83 Activating screen: DynamicPopupMenu 16:53:18.92 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:18.92 MatchInternal::SetMatchType - new type 14 - updating server 16:53:18.92 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:18.92 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:18.92 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:18.92 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:18.92 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:18.93 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:18.93 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:18.93 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:18.94 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:18.95 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:19.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.6, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 16:53:19.85 Activating screen: DynamicPopupMenu 16:53:20.79 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:20.79 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:20.79 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:20.79 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:20.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:21.70 Activating screen: RaceSelectionPopup 16:53:23.81 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:23.81 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:23.81 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:23.81 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:23.82 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:24.63 Activating screen: RaceSelectionPopup 16:53:25.47 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:25.47 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:25.47 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:25.48 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:25.49 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:29.76 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:29.76 Net::Session::SetVisible - session is set to INVISIBLE. 16:53:29.76 GetMaxFrameTimeFromProfile: players=6 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 16:53:29.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:29.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.44 GameSetupForm - No win/loss reporting and only a single player, disconnecting 16:53:32.44 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 16:53:32.44 SetVisible called while !IsConnected 16:53:32.44 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.44 GameSetupForm - Starting game 16:53:32.44 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.44 PopulateGameInfo - random seed:[1493646812], guid:[{1933b469-cfaa-4745-b205-95359843380e}], sync level:[2] 16:53:32.44 Error loading [DATA:levelingCurve.lua] 16:53:32.44 Error loading [DATA:levelingCurve.lua] 16:53:32.44 Error loading [DATA:levelingCurve.lua] 16:53:32.44 Error loading [DATA:levelingCurve.lua] 16:53:32.45 Error loading [DATA:levelingCurve.lua] 16:53:32.45 Error loading [DATA:levelingCurve.lua] 16:53:32.45 MOD - Setting player (0) race to: axis 16:53:32.45 MOD - Setting player (0) race to: 3 16:53:32.45 MOD - Setting player (1) race to: axis 16:53:32.45 MOD - Setting player (1) race to: 3 16:53:32.45 MOD - Setting player (2) race to: axis 16:53:32.45 MOD - Setting player (2) race to: 3 16:53:32.45 MOD - Setting player (3) race to: allies_soviets 16:53:32.45 MOD - Setting player (3) race to: 2 16:53:32.45 MOD - Setting player (4) race to: allies_commonwealth 16:53:32.45 MOD - Setting player (4) race to: 0 16:53:32.45 MOD - Setting player (5) race to: allies 16:53:32.45 MOD - Setting player (5) race to: 1 16:53:32.45 Activating screen: MessageBoxPopup 16:53:32.45 Session is marking the local peer PeerID 1 for death 16:53:32.45 Session::GetEvent DestroyPeer success Peer 1 removed 16:53:32.45 Session::DestroyPeer - deallocating peer 1 16:53:32.45 Session - Requested Disconnect for connectionID 2 16:53:32.45 Deallocated route ID=3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 16:53:32.45 Session - Requested Disconnect for connectionID 3 16:53:32.45 Closed route 3<>3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 16:53:32.45 AdvertisementInternal::Process - EVENT_DESTROYPEER 16:53:32.45 Destroyed Matchinfo 16:53:32.45 APP -- Game Start 16:53:32.45 Sent message game CompanyOfHeroes allowtraffic 16:53:32.45 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.45 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.45 GameInfo::ResetInfo - SyncLevel set to 0 on reset 16:53:32.45 MessageInternal::DestroyChannel: Destroyed channel 534d5347 16:53:32.46 GAME -- Ending FE 16:53:32.46 UIFrontEnd - Unloading Front End 16:53:32.46 Transport - Largest sent is now 8 16:53:32.47 SOUND -- Shutting down ... 16:53:32.52 SOUND -- Shutdown completed! 16:53:32.53 16:53:32.53 GAME -- *** Beginning mission 6p_lenin (1 Humans, 5 Computers) *** 16:53:32.53 16:53:32.58 GAME -- Recording game 16:53:32.61 Activating screen: GameLoadScreen 16:53:32.61 ReportGameStartStats - no connection to server unable to report 16:53:32.61 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 16:53:32.61 PeerRemoveAll - flushing local session peer data 16:53:32.61 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 16:53:32.61 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 16:53:32.73 THREAD: Hyper-Threading Technology Processors are not detected. 16:53:32.75 SOUND -- Initializing ... 16:53:33.10 SOUND -- Initialization completed! 16:53:33.11 PHYSICS: detected processor(s) capable of handling 8 threads. 16:53:33.11 MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_lenin' 16:53:33.12 MOD -- Using Mod 'Eastern_Front' 16:53:33.12 Unable to load/parse precache file [DATA:scenarios\mp\6p_lenin_precache.lua] 16:53:34.17 GameObjLoader - upgrading load_count from 0 to 1212 16:53:35.99 GameObjLoader - upgrading load_count from 1212 to 2032 16:53:36.09 PHYSICS -- Created node factory 'HVOK' 16:53:36.09 PHYSICS -- Created node factory 'DMMY' 16:53:44.51 SPDx9 -- 3605MB available Texture Memory. Unable to create 2x2 texture. 16:53:44.63 GameObjLoader - upgrading load_count from 1938 to 5032 16:53:48.01 GameObjLoader - upgrading load_count from 2052 to 3658 16:53:48.16 GameObjLoader 083c6528 - resetting counters 16:53:48.16 GameObjLoader 083c6528 - LOAD_DONE 16:53:48.16 GAME - SessionSetup 16:53:48.20 CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP. 16:53:48.20 TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024 16:53:48.20 TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024 16:53:48.96 GAME - CreateGEWorld in 799 ms 16:53:48.97 TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed. 16:53:49.05 GAME - SessionSetup finished in 889 ms 16:53:49.05 GAME - WaterReflectionManagerSetup 16:53:49.05 GAME - WaterReflectionManagerSetup finished in 0 ms 16:53:49.06 MessageInternal::CreateChannel: Created channel 474d4343 [Cope FactionFix] - Trying to hook function [Cope FactionFix] - Got LuaState @0x076746a8 [Cope FactionFix] - patched @0x60fc5491 [Cope FactionFix] - hooked @0x60fc5492 [Cope FactionFix] - patched @0x60fc4a07 [Cope FactionFix] - hooked @0x60fc4a07 [Cope FactionFix] - Hook installed 16:53:49.13 ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded. 16:53:49.25 MOD -- Player (unused player) (frame 0) (KillPlayer) 16:53:49.25 MOD -- Player (unused player) (frame 0) (KillPlayer) 16:53:49.45 MessageInternal::CreateChannel: Created channel 4d4f444d 16:53:49.79 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 16:53:49.79 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 16:53:49.79 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 16:53:49.79 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 16:53:49.79 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 16:53:50.11 SPEECHMANAGER -- Loaded in 0.297457 seconds 16:53:50.86 GameObjLoader - upgrading load_count from 0 to 205 16:53:51.16 GameObjLoader 083c6688 - resetting counters 16:53:51.16 GameObjLoader 083c6688 - LOAD_DONE 16:53:51.76 PreloadResources took 564ms. 16:53:51.78 GAME -- Loading completed (19 seconds) 16:53:51.78 SIM -- Setting SyncErrorChecking level to None 16:53:55.39 Activating screen: GameScreen 16:53:55.39 Activating screen: Decorators_widescreen 16:53:55.39 Activating screen: Taskbar_widescreen 16:53:55.39 Activating screen: SubtitleScreen 16:53:55.39 Activating screen: TextOverlayScreen 16:53:55.43 PerformanceRecorder::StartRecording for game size 6 16:53:55.43 GAME -- Starting mission... 16:53:55.64 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 16:53:55.64 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 16:53:55.64 MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI) 16:53:55.64 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 16:53:55.64 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 16:54:00.39 Warning: non auto-match upgrade not found in AE, tuning 16:54:01.53 Warning: binding repeat_2(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12] 16:54:01.54 Warning: binding repeat_5(Ability: abilities\smg_mp40) -- ui index '0' out of bounds; range is [1, 12] 16:54:20.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 16:54:21.40 GAME -- SimulationController::Pause 0 16:54:26.82 GAME -- SimulationController::Pause 1 16:54:33.36 GAME -- SimulationController::Pause 0 16:54:35.92 GAME -- SimulationController::Pause 1 16:54:39.51 GAME -- SimulationController::Pause 0 16:54:40.43 GAME -- SimulationController::Pause 1 16:55:21.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 16:56:22.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 16:56:55.15 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 16:57:23.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 16:57:31.91 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 16:58:18.53 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 16:58:24.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 16:58:25.03 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 16:58:42.15 Unabled to do upgrade, CANPRODUCE_NoResources 16:59:04.03 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 16:59:25.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:00:26.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:00:57.54 Unable to bind updater for fx [ui\reveal]. It could be looping in a fire-n-forget action. 17:01:27.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:01:56.17 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 17:01:56.17 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 17:01:56.17 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:02:00.20 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 17:02:00.20 Muting spew of bind updater. Fix art and re-test. 17:02:28.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:02:31.31 Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background' 17:02:50.30 Was already stealing a skeleton when told to steal another [heavymachinegun_target]. 17:03:29.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:03:53.50 Warning: binding repeat_3(Production Item: sbps\races\axis\vehicles\halftrack_squad_with_pioneer) -- ui index '0' out of bounds; range is [1, 12] 17:04:30.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:04:45.16 Warning: binding repeat_4(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 17:05:31.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:05:55.30 Was already stealing a skeleton when told to steal another [mortar_target]. 17:05:56.21 Was already stealing a skeleton when told to steal another [mortar_target]. 17:05:56.30 Warning: binding repeat_1(Ability: abilities\killing_medic_axis_sniper) -- ui index '0' out of bounds; range is [1, 12] 17:05:56.30 Warning: binding repeat_3(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 17:06:32.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:07:33.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:08:34.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:08:59.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:00.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:01.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:02.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:10.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:11.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:09:35.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:10:36.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:10:43.37 Activating screen: Command_Tree 17:10:43.93 Activating screen: Command_Branch 17:10:46.06 Activating screen: Command_Branch 17:10:50.43 Activating screen: Command_Branch 17:11:37.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:12:10.80 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:21.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:21.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:22.81 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:22.81 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:23.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:24.82 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:25.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:26.82 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:27.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:28.79 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:29.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:12:38.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 17:13:39.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 17:14:17.90 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 17:14:17.91 Transport - Largest sent is now 19 17:14:17.99 Transport - Largest sent is now 30 17:14:40.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.1/0.5, max unsent 0, version err 0, merge 1 17:15:29.44 Activating screen: Command_Branch 17:15:41.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.3/0.7, max unsent 0, version err 0, merge 0 17:15:41.38 Was already stealing a skeleton when told to steal another [mortar_target]. 17:15:45.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:46.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:47.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:48.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:49.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:56.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:57.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:58.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:15:59.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:00.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:01.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:02.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:03.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:04.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:05.29 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:06.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:07.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:08.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:09.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:10.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:11.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:12.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:18.28 Unabled to do upgrade, CANPRODUCE_NoResources 17:16:42.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.1/0.2, max unsent 0, version err 0, merge 0 17:17:25.03 Unabled to do upgrade, CANPRODUCE_NoResources 17:17:26.03 Unabled to do upgrade, CANPRODUCE_NoResources 17:17:43.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:17:53.93 Activating screen: Command_Branch 17:18:02.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:18:03.78 Unabled to do upgrade, CANPRODUCE_NoResources 17:18:04.80 Unabled to do upgrade, CANPRODUCE_NoResources 17:18:44.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.5, max unsent 0, version err 0, merge 1 17:18:48.78 SimulationController::GenerateSyncCmd - couldn't send all commands, 2 binned. 17:19:45.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.6, max unsent 0, version err 0, merge 0 17:20:24.28 FXShader::SetVariable( "tex0", T ) uses unknown variable. 17:20:46.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.5, max unsent 0, version err 0, merge 0 17:21:47.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 1 17:22:04.07 Activating screen: pause_menu 17:22:04.07 GAME -- SimulationController::Pause 0 17:22:05.33 Activating screen: prompt_generic 17:22:05.93 MOD -- Player KMARTIS-PC (network abort) (frame 13436) (KillPlayer) 17:22:05.93 GameApp::ReportStats 17:22:05.93 ReportStats - untracked game, no need to report match 4294967295:4294967295 17:22:05.93 ReportSimStats - nothing to report, no connection to the server 17:22:05.93 ReportStats - ReportSimStats failed 17:22:05.93 GameObj::DoGameOverPopup 17:22:05.93 Activating screen: GameOverDialog 17:22:05.93 GAME -- SimulationController::Pause 1 17:22:05.93 ArbitrationMessageNeeded 0 allies vs 0 enemies result 0 17:22:05.93 GameOverDialog - normal game ending (no sync errors) 17:22:07.55 Activating screen: postgame_stats_skirm 17:22:09.10 Activating screen: AppRestartingForm 17:22:09.10 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 17:22:09.10 Widget attempting to remove a non-existance listener. Widget:bkg_COH 17:22:09.10 Widget attempting to remove a non-existance listener. Widget:bkg_CXP1 17:22:09.10 Widget attempting to remove a non-existance listener. Widget:bkg_CXP2 17:22:09.10 Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1 17:22:09.10 Widget attempting to remove a non-existance listener. Widget:bkg_ToV 17:22:09.11 APP -- Game Stop 17:22:09.11 GameApp::TransitionGameStop 17:22:09.11 GameApp::TransitionQuitCommon 17:22:09.11 PerformanceRecorder: Updating profile history 17:22:09.11 GetMaxFrameTimeFromProfile: players=6 expected FPS=71.967613, bars=5, max avg=0.014, sd=0.000, 1 samples = 0.01 17:22:09.11 PerformanceRecorder::EndRecording - game size=6, max average=0.013895, worst frame=0.013895 17:22:09.11 Recording: No [6 players] 17:22:09.11 Total render, sim time: 8.43, 13.23 sec 17:22:09.11 Total render, sim frames: 1993, 904 17:22:09.11 Max/Avg: 0.01, 0.01 sec (fps=71.97, 107.76) (84 samples) 17:22:09.11 Bars: 5 17:22:09.11 Profile Max Performance[6 players] = 0.01 0.00 0.00 0.00 0.00 17:22:09.11 Profile Avg Performance[6 players] = 0.01 0.00 0.00 0.00 0.00 17:22:09.11 GameObj::ShutdownGameObj 17:22:09.11 GAME -- Ending mission (Game over) 17:22:09.11 GameObjLoader 083c6688 - Unload 17:22:09.11 GameObjLoader 083c6688 - resetting counters 17:22:09.14 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 17:22:09.14 MOD -- Game Over at frame 13436 17:22:09.22 GameObjLoader 083c6528 - Unload 17:22:09.22 GameObjLoader 083c6528 - resetting counters 17:22:09.26 SOUND -- Shutting down ... 17:22:09.32 SOUND -- Shutdown completed! 17:22:09.32 MessageInternal::DestroyChannel: Destroyed channel 474d4343 17:22:09.32 GAME -- Beginning FE 17:22:09.32 Sent message game CompanyOfHeroes allowtraffic 17:22:09.32 UIFrontEnd - Loading Front End 17:22:09.32 THREAD: Hyper-Threading Technology Processors are not detected. 17:22:09.34 SOUND -- Initializing ... 17:22:09.37 SOUND -- Initialization completed! 17:22:09.37 UIFrontEnd - Initializing Forms 17:22:10.26 CampaignFilter::BindFilterSpecificWidgets() 17:22:10.29 Turning off auto-selection of profiles, we already have a profile selected. 17:22:10.29 MOD -- Mounting Eastern_Front_DLC1, 1.0 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\Movies\' 17:22:10.29 MOD -- Mounting Eastern_Front_DLC2, 1.0 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\Movies\' 17:22:10.29 MOD -- Mounting Eastern_Front_DLC3, 1.0 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\DataArtHigh\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\DataSoundEnglish\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\Movies\' 17:22:10.29 MOD -- Mounting Eastern_Front_OPS, 1.0 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Ops\Data\' 17:22:10.29 MOD -- Mounting Eastern_Front, 1.0 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\RelicOnline\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Engine\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'WW2\Data\' 17:22:10.29 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\WW2\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtHigh\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\DataArtHigh\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataSoundHigh\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataSound\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\' 17:22:10.30 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Engine\Locale\English\Data\' 17:22:10.32 FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\' 17:22:10.32 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\DataSoundEnglish\' 17:22:10.32 FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\' 17:22:10.32 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Locale\English\Data\' 17:22:10.33 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataAttrib\' 17:22:10.33 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Movies\' 17:22:10.33 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Engine\Movies\' 17:22:10.33 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\WW2\Movies\' 17:22:10.33 Activating screen: AppRestartingForm 17:22:10.33 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 17:22:10.33 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 17:22:10.80 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 17:22:10.80 CRC & Version Info : 0000025a:703d9745:ec3c9e0d eastern_front:602:factionfix.dll 1 17:22:10.80 Activating screen: FEMovie 17:22:10.80 Activating screen: OnlineWidget 17:22:10.80 Activating screen: OnlineSelectGame 17:22:10.82 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 17:22:10.82 Activating screen: RelicOnlineChat 17:22:10.82 Activating screen: RelicOnlineNewsScreen 17:22:10.82 Activating screen: RelicOnlineStatsScreen 17:22:10.82 Activating screen: Achievements 17:22:10.82 GAME -- Setting campaign state to 'coh' 17:22:10.82 GAME -- Closing state 'coh' 17:22:10.82 GAME -- Setting campaign state to 'cxp2' 17:22:10.82 GAME -- Closing state 'cxp2' 17:22:10.82 GAME -- Setting campaign state to 'cxp1' 17:22:10.82 GAME -- Closing state 'cxp1' 17:22:10.82 GAME -- Setting campaign state to 'dlc1' 17:22:10.82 GAME -- Closing state 'dlc1' 17:22:10.82 GAME -- Setting campaign state to 'dlc2' 17:22:10.82 GAME -- Closing state 'dlc2' 17:22:10.82 GAME -- Setting campaign state to 'dlc3' 17:22:10.82 GAME -- Closing state 'dlc3' 17:22:10.82 GAME -- Setting campaign state to 'dlc1' 17:22:10.82 GAME -- Closing state 'dlc1' 17:22:10.83 Activating screen: GameHistory 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:10.83 Activating screen: OnlineSelectGame 17:22:10.83 Activating screen: RelicOnlineTabs 17:22:10.83 Activating screen: ScreenTipPopup 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:10.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:14.29 Activating screen: OnlineGameCreate 17:22:14.29 Activating screen: RelicOnlineTabs 17:22:16.83 Activating screen: MessageBoxPopup 17:22:16.83 Created Matchinfo 17:22:16.83 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 17:22:16.83 starting LAN hosting 17:22:16.84 OnJoinAdvertisementSuccess - joined online match, server leave notification required 17:22:16.84 starting local hosting 17:22:16.84 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 17:22:16.84 Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112; 17:22:16.84 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 17:22:16.84 Session::Host sid = 8DD5CF6E41B749B1, hostURL = , local addresses = WINaddr:[IP address redacted]:6112; 17:22:16.84 ValidateCustomData: called with 205 bytes of custom data 17:22:16.84 Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112; 17:22:16.84 AdvertisementInternal::Process - EVENT_NEWPEER 17:22:16.84 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 17:22:16.84 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 17:22:16.84 hosting - Session is connected 17:22:16.84 Net::Session::SetVisible - session is set to VISIBLE. 17:22:16.85 hosting completed successfully 17:22:16.85 HostAsync - completed with HostResult = 0 17:22:16.85 Activating screen: OnlineGameSetup 17:22:16.85 Activating screen: RelicOnlineTabs 17:22:16.85 AutomatchInternal::OnHostComplete - Completed Host with success=1 17:22:16.85 AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring 17:22:16.85 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 17:22:16.85 MessageInternal::CreateChannel: Created channel 534d5347 17:22:16.85 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:16.85 Net::Session::SetVisible - session is set to VISIBLE. 17:22:16.86 GetMaxFrameTimeFromProfile: players=6 expected FPS=71.967613, bars=5, max avg=0.014, sd=0.000, 1 samples = 0.01 17:22:16.86 ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1 17:22:16.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:16.86 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 17:22:16.87 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:22:16.87 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:16.87 Net::Session::SetVisible - session is set to VISIBLE. 17:22:16.87 GetMaxFrameTimeFromProfile: players=6 expected FPS=71.967613, bars=5, max avg=0.014, sd=0.000, 1 samples = 0.01 17:22:16.89 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:16.91 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:21.28 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:21.28 Net::Session::SetVisible - session is set to VISIBLE. 17:22:21.28 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:21.30 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:21.32 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:24.56 Activating screen: DynamicPopupMenu 17:22:24.68 OnlinePlayerListEntry::OnEnterSlot - Slot 4 selected... 17:22:24.68 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:24.68 Net::Session::SetVisible - session is set to VISIBLE. 17:22:24.68 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:24.69 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:24.70 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:28.80 Activating screen: DynamicPopupMenu 17:22:28.88 OnlinePlayerListEntry::OnEnterSlot - Slot 0 selected... 17:22:28.89 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:28.89 Net::Session::SetVisible - session is set to VISIBLE. 17:22:28.89 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:28.89 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:28.90 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:30.43 Activating screen: DynamicPopupMenu 17:22:31.21 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:31.21 Net::Session::SetVisible - session is set to VISIBLE. 17:22:31.21 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:31.21 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:31.22 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:31.90 Activating screen: DynamicPopupMenu 17:22:32.75 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:32.75 Net::Session::SetVisible - session is set to VISIBLE. 17:22:32.75 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:32.75 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:32.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:33.19 Activating screen: DynamicPopupMenu 17:22:34.96 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:34.96 Net::Session::SetVisible - session is set to VISIBLE. 17:22:34.96 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:34.96 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:34.98 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:37.03 Activating screen: RaceSelectionPopup 17:22:38.92 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:38.92 Net::Session::SetVisible - session is set to VISIBLE. 17:22:38.92 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:38.92 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:38.93 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:40.11 Activating screen: RaceSelectionPopup 17:22:40.95 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:40.95 Net::Session::SetVisible - session is set to VISIBLE. 17:22:40.95 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:40.95 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:40.96 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:41.98 Activating screen: DynamicPopupMenu 17:22:43.39 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:43.39 Net::Session::SetVisible - session is set to VISIBLE. 17:22:43.39 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:43.39 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:43.40 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:44.26 Activating screen: DynamicPopupMenu 17:22:45.01 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:45.01 Net::Session::SetVisible - session is set to VISIBLE. 17:22:45.01 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:45.01 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:45.03 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:45.50 Activating screen: DynamicPopupMenu 17:22:46.40 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 17:22:46.40 Net::Session::SetVisible - session is set to VISIBLE. 17:22:46.40 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:46.41 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:46.42 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:47.08 Activating screen: DynamicPopupMenu 17:22:47.83 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:22:47.83 MatchInternal::SetMatchType - new type 14 - updating server 17:22:47.83 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:22:47.83 Net::Session::SetVisible - session is set to INVISIBLE. 17:22:47.83 Net::Session::SetVisible - session is set to INVISIBLE. 17:22:47.83 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:47.83 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:47.84 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:22:47.84 Net::Session::SetVisible - session is set to INVISIBLE. 17:22:47.84 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:47.84 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:47.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:48.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.4, #p/sec[s/r] = 0.2/0.5, max unsent 0, version err 0, merge 0 17:22:50.13 Activating screen: DynamicPopupMenu 17:22:53.63 Activating screen: RaceSelectionPopup 17:22:54.44 Activating screen: RaceSelectionPopup 17:22:58.78 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:22:58.78 Net::Session::SetVisible - session is set to INVISIBLE. 17:22:58.78 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:22:58.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:58.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:22:59.54 Activating screen: RaceSelectionPopup 17:23:00.31 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:23:00.31 Net::Session::SetVisible - session is set to INVISIBLE. 17:23:00.32 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:23:00.32 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:00.33 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:04.40 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:23:04.40 Net::Session::SetVisible - session is set to INVISIBLE. 17:23:04.40 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:23:04.41 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:04.43 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:05.91 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:23:05.91 Net::Session::SetVisible - session is set to INVISIBLE. 17:23:05.91 GetMaxFrameTimeFromProfile: players=8 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples = 17:23:05.93 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:05.95 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:23:17.00 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 17:23:17.00 MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=13/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=11/0, frag=0/0, Errors=0/0 17:23:49.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 1 17:24:18.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 17:24:18.01 MessageCounts: inval=0/0, seek=0/12, 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=13/18, frag=0/0, Errors=0/0 17:24:50.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:25:19.00 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 17:25:19.00 MessageCounts: inval=0/0, seek=0/8, 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=12/11, frag=0/0, Errors=0/0 17:25:51.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.5, max unsent 0, version err 0, merge 1 17:26:20.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 17:26:20.01 MessageCounts: inval=0/0, seek=0/7, 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=14/10, frag=0/0, Errors=0/0 17:26:52.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 1 17:27:21.00 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 17:27:21.00 MessageCounts: inval=0/0, seek=0/13, 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=12/16, frag=0/0, Errors=0/0 17:27:53.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 1 17:28:22.00 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 17:28:22.00 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=10/0, frag=0/0, Errors=0/0 17:28:54.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:29:23.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 17:29:23.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=11/0, frag=0/0, Errors=0/0 17:29:55.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:30:24.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 17:30:24.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=10/0, frag=0/0, Errors=0/0 17:30:56.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:31:25.00 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 17:31:25.00 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=10/0, frag=0/0, Errors=0/0 17:31:57.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:32:26.00 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 17:32:26.00 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=10/0, frag=0/0, Errors=0/0 17:32:58.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:33:27.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 17:33:27.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=10/0, frag=0/0, Errors=0/0 17:33:59.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:34:28.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 17:34:28.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=10/0, frag=0/0, Errors=0/0 17:35:00.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:35:29.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 17:35:29.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=11/0, frag=0/0, Errors=0/0 17:36:01.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:36:30.00 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 17:36:30.00 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=10/0, frag=0/0, Errors=0/0 17:37:02.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:37:31.00 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 17:37:31.00 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=10/0, frag=0/0, Errors=0/0 17:38:03.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:38:32.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 17:38:32.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=10/0, frag=0/0, Errors=0/0 17:39:04.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:39:33.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 17:39:33.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=10/0, frag=0/0, Errors=0/0 17:40:05.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.0, max unsent 0, version err 0, merge 0 17:40:19.62 GameSetupForm - No win/loss reporting and only a single player, disconnecting 17:40:19.62 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 17:40:19.62 SetVisible called while !IsConnected 17:40:19.62 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:40:19.63 GameSetupForm - Starting game 17:40:19.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:40:19.63 PopulateGameInfo - random seed:[1493649619], guid:[{bd615895-c158-48a4-9a6b-2412a095a9c3}], sync level:[2] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 Error loading [DATA:levelingCurve.lua] 17:40:19.63 MOD - Setting player (0) race to: allies 17:40:19.63 MOD - Setting player (0) race to: 1 17:40:19.63 MOD - Setting player (1) race to: allies_soviets 17:40:19.63 MOD - Setting player (1) race to: 2 17:40:19.63 MOD - Setting player (2) race to: allies 17:40:19.63 MOD - Setting player (2) race to: 1 17:40:19.63 MOD - Setting player (3) race to: allies_commonwealth 17:40:19.63 MOD - Setting player (3) race to: 0 17:40:19.63 MOD - Setting player (4) race to: axis 17:40:19.63 MOD - Setting player (4) race to: 3 17:40:19.63 MOD - Setting player (5) race to: axis_ostheer 17:40:19.63 MOD - Setting player (5) race to: 4 17:40:19.63 MOD - Setting player (6) race to: axis_ostheer 17:40:19.63 MOD - Setting player (6) race to: 4 17:40:19.63 MOD - Setting player (7) race to: axis 17:40:19.63 MOD - Setting player (7) race to: 3 17:40:19.63 Activating screen: MessageBoxPopup 17:40:19.64 Session is marking the local peer PeerID 1 for death 17:40:19.64 Session::GetEvent DestroyPeer success Peer 1 removed 17:40:19.64 Session::DestroyPeer - deallocating peer 1 17:40:19.64 Session - Requested Disconnect for connectionID 4 17:40:19.64 Deallocated route ID=5 for PeerID 1 at WINaddr:[IP address redacted]:6112; 17:40:19.64 Session - Requested Disconnect for connectionID 5 17:40:19.64 Closed route 5<>5 for PeerID 1 at WINaddr:[IP address redacted]:6112; 17:40:19.64 AdvertisementInternal::Process - EVENT_DESTROYPEER 17:40:19.64 Destroyed Matchinfo 17:40:19.64 APP -- Game Start 17:40:19.64 Sent message game CompanyOfHeroes allowtraffic 17:40:19.65 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:40:19.65 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:40:19.65 GameInfo::ResetInfo - SyncLevel set to 0 on reset 17:40:19.65 MessageInternal::DestroyChannel: Destroyed channel 534d5347 17:40:19.65 GAME -- Ending FE 17:40:19.65 UIFrontEnd - Unloading Front End 17:40:19.66 SOUND -- Shutting down ... 17:40:19.71 SOUND -- Shutdown completed! 17:40:19.72 17:40:19.72 GAME -- *** Beginning mission 8p_best (1 Humans, 7 Computers) *** 17:40:19.72 17:40:19.83 GAME -- Recording game 17:40:19.86 Activating screen: GameLoadScreen 17:40:19.86 ReportGameStartStats - no connection to server unable to report 17:40:19.86 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 17:40:19.86 PeerRemoveAll - flushing local session peer data 17:40:19.86 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 17:40:19.86 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 17:40:19.99 THREAD: Hyper-Threading Technology Processors are not detected. 17:40:20.01 SOUND -- Initializing ... 17:40:20.37 SOUND -- Initialization completed! 17:40:20.38 PHYSICS: detected processor(s) capable of handling 8 threads. 17:40:20.39 MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\8p_best\8p_best' 17:40:20.39 MOD -- Using Mod 'Eastern_Front' 17:40:20.39 Unable to load/parse precache file [DATA:scenarios\mp\classic\8p_best\8p_best_precache.lua] 17:40:21.44 GameObjLoader - upgrading load_count from 0 to 1185 17:40:23.29 GameObjLoader - upgrading load_count from 1185 to 2031 17:40:26.55 GameObjLoader - upgrading load_count from 2031 to 2303 17:40:34.76 SPDx9 -- 3599MB available Texture Memory. Unable to create 2x2 texture. 17:40:34.87 GameObjLoader - upgrading load_count from 2303 to 5433 17:40:39.12 GameObjLoader 083c6528 - resetting counters 17:40:39.12 GameObjLoader 083c6528 - LOAD_DONE 17:40:39.12 GAME - SessionSetup 17:40:39.17 CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP. 17:40:39.17 TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024 17:40:39.17 TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024 17:40:40.16 GAME - CreateGEWorld in 1048 ms 17:40:40.17 GAME - SessionSetup finished in 1058 ms 17:40:40.18 GAME - WaterReflectionManagerSetup 17:40:40.18 GAME - WaterReflectionManagerSetup finished in 0 ms 17:40:40.18 MessageInternal::CreateChannel: Created channel 474d4343 17:40:40.18 Regenerating ImpassMap data... 17:40:40.18 Impass Data was already valid, but regenerating... 17:40:40.27 Generating CanBuild Map. THIS SHOULD ONLY HAPPEN IN WORLDBUILDER! IF YOU SEE THIS IN GAME, RE-SAVE THE MAP! 17:40:40.27 Regenerating CanBuildMap data... 17:40:40.27 Generating CanShoot Map. 17:40:40.27 Pathfinder::Regenerate()... 17:40:40.29 Generating PathSectorMap... 17:40:40.46 Pathfinder::Regenerate() Done. [Cope FactionFix] - Trying to hook function [Cope FactionFix] - Got LuaState @0x076746a8 [Cope FactionFix] - Hook already installed 17:40:40.53 ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded. 17:40:41.01 MessageInternal::CreateChannel: Created channel 4d4f444d 17:40:41.36 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 17:40:41.36 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 17:40:41.36 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 17:40:41.37 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 17:40:41.37 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 17:40:41.68 SPEECHMANAGER -- Loaded in 0.298664 seconds 17:40:42.54 GameObjLoader - upgrading load_count from 0 to 244 17:40:42.99 GameObjLoader 083c6688 - resetting counters 17:40:42.99 GameObjLoader 083c6688 - LOAD_DONE 17:40:43.78 PreloadResources took 746ms. 17:40:43.80 GAME -- Loading completed (24 seconds) 17:40:43.80 SIM -- Setting SyncErrorChecking level to None 17:40:46.51 Activating screen: GameScreen 17:40:46.51 Activating screen: Decorators_widescreen 17:40:46.51 Activating screen: Taskbar_widescreen 17:40:46.51 Activating screen: SubtitleScreen 17:40:46.51 Activating screen: TextOverlayScreen 17:40:46.54 PerformanceRecorder::StartRecording for game size 8 17:40:46.54 GAME -- Starting mission... 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:46.76 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI) 17:40:47.64 SimulationController::GenerateSyncCmd - couldn't send all commands, 3 binned. 17:40:51.52 Warning: non auto-match upgrade not found in AE, tuning 17:40:53.72 GAME -- SimulationController::Pause 0 17:41:06.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:42:07.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:43:08.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:44:09.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:45:10.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:46:11.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:46:24.10 GAME -- SimulationController::Pause 1 17:46:30.35 GAME -- SimulationController::Pause 0 17:47:12.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:48:13.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:49:14.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:50:15.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:51:16.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:52:17.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:53:18.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:54:19.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:55:20.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:56:21.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 17:57:22.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:58:23.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 17:59:24.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:00:25.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:01:26.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:02:27.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 18:03:28.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:04:29.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:05:30.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:06:31.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:07:32.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:08:33.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.3, max unsent 0, version err 0, merge 0 18:09:34.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:10:35.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0 18:10:47.19 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:11:36.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 0.4/0.7, max unsent 0, version err 0, merge 1 18:11:46.12 Transport - Largest received is now 1160 18:12:37.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 2.7/0.6, #p/sec[s/r] = 0.5/1.2, max unsent 0, version err 0, merge 0 18:12:50.05 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:13:00.66 GAME -- SimulationController::Pause 1 18:13:26.07 Transport - Largest received is now 1161 18:13:38.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/2.5, #p/sec[s/r] = 0.7/2.2, max unsent 0, version err 0, merge 1 18:14:39.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/1.5, max unsent 0, version err 0, merge 0 18:15:40.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:16:41.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:17:42.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:18:43.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.8/0.8, max unsent 0, version err 0, merge 0 18:19:44.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:20:12.17 Was already stealing a skeleton when told to steal another [mortar_target]. 18:20:45.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:21:27.31 Was already stealing a skeleton when told to steal another [mortar_target]. 18:21:46.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:22:13.99 SimulationController::GenerateSyncCmd - couldn't send all commands, 2 binned. 18:22:47.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:23:24.56 Was already stealing a skeleton when told to steal another [heavymachinegun_target]. 18:23:48.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.8/0.8, max unsent 0, version err 0, merge 0 18:24:49.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:25:13.65 Was already stealing a skeleton when told to steal another [mortar_target]. 18:25:50.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:26:51.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:27:52.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:28:53.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:29:18.26 Was already stealing a skeleton when told to steal another [mortar_target]. 18:29:18.87 SimulationController::GenerateSyncCmd - couldn't send all commands, 2 binned. 18:29:31.54 Was already stealing a skeleton when told to steal another [mortar_target]. 18:29:36.58 Was already stealing a skeleton when told to steal another [mortar_target]. 18:29:54.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.8/0.8, max unsent 0, version err 0, merge 0 18:29:54.36 SimulationController::GenerateSyncCmd - couldn't send all commands, 3 binned. 18:30:55.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:31:56.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:32:18.87 SimulationController::GenerateSyncCmd - couldn't send all commands, 1 binned. 18:32:23.29 Was already stealing a skeleton when told to steal another [mortar_target]. 18:32:53.52 Was already stealing a skeleton when told to steal another [mortar_target]. 18:32:57.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:33:50.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:33:58.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:34:45.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:46.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:47.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:51.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:51.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:52.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:52.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:53.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:54.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:55.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:56.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:56.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:57.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:58.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:34:59.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:34:59.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:00.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:02.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:03.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:04.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:05.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:06.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:07.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:08.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:09.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:10.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:13.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:14.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:15.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:16.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:17.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:18.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:18.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:19.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:19.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:20.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:20.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:21.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:21.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:22.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:22.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:23.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:24.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:25.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:26.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:29.87 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:30.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:31.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:35.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:36.36 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:37.37 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:40.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:35:41.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:36:00.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:36:24.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:01.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:37:46.99 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:47.99 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:48.99 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:49.99 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:52.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:52.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:53.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:53.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:54.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:54.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:55.50 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:56.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:57.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:58.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:37:59.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:02.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.7/0.8, max unsent 0, version err 0, merge 0 18:38:03.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:05.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:06.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:07.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:08.99 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:08.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:10.01 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:10.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:11.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:12.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:14.01 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:14.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:16.00 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:16.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:17.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:18.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:20.00 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:20.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:21.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:22.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:23.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:24.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:25.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:25.72 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:38:25.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:26.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:26.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:27.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:27.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:28.49 Unabled to do upgrade, CANPRODUCE_NoResources 18:38:28.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:31.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:32.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:33.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:34.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:35.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:36.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:37.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:38.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:39.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:40.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:42.00 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:42.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:43.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:45.00 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:45.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:46.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:47.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:48.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:49.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:50.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:51.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:52.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:54.00 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:54.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:55.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:56.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:57.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:58.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:38:59.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:39:00.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:39:01.99 An ability failed to cast because target validation failed: abilities\v1_target_ability 18:39:03.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 0.9/1.1, max unsent 0, version err 0, merge 1 18:39:24.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:39:25.86 Unabled to do upgrade, CANPRODUCE_NoResources 18:39:26.88 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:39:59.62 Unabled to do upgrade, CANPRODUCE_NoResources 18:40:00.62 Unabled to do upgrade, CANPRODUCE_NoResources 18:40:01.62 Unabled to do upgrade, CANPRODUCE_NoResources 18:40:04.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.1/1.6, max unsent 0, version err 0, merge 1 18:41:05.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.3/1.8, max unsent 0, version err 0, merge 0 18:42:06.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/1.5, #p/sec[s/r] = 1.3/2.0, max unsent 0, version err 0, merge 0 18:42:21.83 GAME -- SimulationController::Pause 0 18:43:07.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/3.9, #p/sec[s/r] = 1.3/4.9, max unsent 0, version err 0, merge 0 18:43:56.13 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:44:08.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.3/1.6, max unsent 0, version err 0, merge 1 18:45:09.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/1.9, max unsent 0, version err 0, merge 0 18:46:10.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/1.8, max unsent 0, version err 0, merge 0 18:47:11.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/1.9, max unsent 0, version err 0, merge 0 18:48:12.01 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.1, #p/sec[s/r] = 1.6/1.9, max unsent 0, version err 0, merge 0 18:49:13.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.1, #p/sec[s/r] = 1.5/1.9, max unsent 0, version err 0, merge 0 18:50:14.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 1.6/1.8, max unsent 0, version err 0, merge 0 18:51:15.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/1.9, max unsent 0, version err 0, merge 0 18:52:08.35 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:52:16.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/1.9, max unsent 0, version err 0, merge 0 18:52:42.69 Transport - Largest received is now 1304 18:52:43.50 Transport - Largest received is now 1318 18:53:17.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/2.9, max unsent 0, version err 0, merge 0 18:54:18.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.9, max unsent 0, version err 0, merge 0 18:55:19.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.8, max unsent 0, version err 0, merge 0 18:56:20.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/1.4, max unsent 0, version err 0, merge 0 18:57:21.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/1.3, max unsent 0, version err 0, merge 0 18:57:50.03 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 18:58:22.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.7, max unsent 0, version err 0, merge 1 18:59:23.01 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.1, #p/sec[s/r] = 2.0/1.9, max unsent 0, version err 0, merge 0 19:00:24.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.1, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:01:25.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:02:26.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.9, max unsent 0, version err 0, merge 0 19:03:27.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:04:28.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:05:29.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:06:30.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:07:31.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:08:32.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.8, max unsent 0, version err 0, merge 0 19:09:33.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.9, max unsent 0, version err 0, merge 0 19:10:34.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 1.9/1.9, max unsent 0, version err 0, merge 0 19:11:00.48 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 19:11:35.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 2.0/2.1, max unsent 0, version err 0, merge 1 19:12:36.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 2.2/2.5, max unsent 0, version err 0, merge 0 19:13:37.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.3, #p/sec[s/r] = 2.2/2.5, max unsent 0, version err 0, merge 0 19:14:38.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.7, #p/sec[s/r] = 2.1/3.5, max unsent 0, version err 0, merge 0 19:15:39.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.5, #p/sec[s/r] = 2.2/2.5, max unsent 0, version err 0, merge 0 19:16:40.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 2.1/3.2, max unsent 0, version err 0, merge 0 19:17:41.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.2/2.5, max unsent 0, version err 0, merge 0 19:18:42.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.2/1.9, max unsent 0, version err 0, merge 0 19:19:43.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.2/1.9, max unsent 0, version err 0, merge 0 19:20:44.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.1/1.8, max unsent 0, version err 0, merge 0 19:21:45.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 2.1/1.9, max unsent 0, version err 0, merge 0 19:22:46.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.0/1.8, max unsent 0, version err 0, merge 0 19:23:47.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.0/1.9, max unsent 0, version err 0, merge 0 19:24:48.01 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 2.1/1.8, max unsent 0, version err 0, merge 0 19:25:49.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.4, max unsent 0, version err 0, merge 0 19:26:50.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.2, max unsent 0, version err 0, merge 0 19:27:51.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.3, max unsent 0, version err 0, merge 0 19:28:52.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.3, max unsent 0, version err 0, merge 0 19:29:53.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/1.2, max unsent 0, version err 0, merge 1 19:30:54.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.9, #p/sec[s/r] = 1.8/1.4, max unsent 0, version err 0, merge 0 19:31:55.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.2, #p/sec[s/r] = 1.7/1.4, max unsent 0, version err 0, merge 0 19:32:56.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.6, #p/sec[s/r] = 1.8/1.5, max unsent 0, version err 0, merge 1 19:33:57.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.3, #p/sec[s/r] = 1.7/1.4, max unsent 0, version err 0, merge 0 19:34:58.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.6, #p/sec[s/r] = 1.7/1.3, max unsent 0, version err 0, merge 0 19:35:59.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.1, #p/sec[s/r] = 1.6/0.8, max unsent 0, version err 0, merge 0 19:37:00.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/0.8, max unsent 0, version err 0, merge 0 19:38:01.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.8, max unsent 0, version err 0, merge 0 19:39:02.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:40:03.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:41:04.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:42:05.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:43:06.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:44:07.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:45:08.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:46:09.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:47:10.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:48:11.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:49:12.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/0.7, max unsent 0, version err 0, merge 0 19:50:13.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.8, max unsent 0, version err 0, merge 0 19:51:14.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.7, max unsent 0, version err 0, merge 0 19:52:15.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.5, max unsent 0, version err 0, merge 0 19:53:16.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.2, max unsent 0, version err 0, merge 0 19:54:17.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/0.3, max unsent 0, version err 0, merge 0 19:55:18.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.2, max unsent 0, version err 0, merge 0 19:56:19.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/0.2, max unsent 0, version err 0, merge 0 19:57:20.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/0.2, max unsent 0, version err 0, merge 0 19:58:21.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.5/0.2, max unsent 0, version err 0, merge 0 19:59:22.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.2, max unsent 0, version err 0, merge 0 20:00:23.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.6, max unsent 0, version err 0, merge 0 20:01:24.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.3, max unsent 0, version err 0, merge 0 20:02:25.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.6/0.5, max unsent 0, version err 0, merge 0 20:03:26.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.0, max unsent 0, version err 0, merge 0 20:04:27.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.2, max unsent 0, version err 0, merge 0 20:05:28.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.2, max unsent 0, version err 0, merge 0 20:06:29.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.0, max unsent 0, version err 0, merge 0 20:07:30.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.2, max unsent 0, version err 0, merge 0 20:08:31.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/1.2, max unsent 0, version err 0, merge 0 20:09:32.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/3.8, #p/sec[s/r] = 1.6/1.6, max unsent 0, version err 0, merge 1 20:10:33.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.3, #p/sec[s/r] = 1.8/1.8, max unsent 0, version err 0, merge 0 20:11:34.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/0.8, max unsent 0, version err 0, merge 0 20:12:35.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/0.8, max unsent 0, version err 0, merge 0 20:13:36.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.8/0.8, max unsent 0, version err 0, merge 0 20:14:37.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.7/0.8, max unsent 0, version err 0, merge 0 20:14:57.18 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:15:38.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.1, max unsent 0, version err 0, merge 1 20:16:39.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.0/1.3, max unsent 0, version err 0, merge 0 20:17:40.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.0/1.3, max unsent 0, version err 0, merge 1 20:18:41.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 1.9/1.2, max unsent 0, version err 0, merge 0 20:18:43.83 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:19:42.01 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/1.9, #p/sec[s/r] = 2.3/2.0, max unsent 0, version err 0, merge 1 20:20:43.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/1.7, #p/sec[s/r] = 2.2/2.5, max unsent 0, version err 0, merge 0 20:21:44.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.5, #p/sec[s/r] = 2.3/2.1, max unsent 0, version err 0, merge 0 20:22:45.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 0 20:23:46.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:24:47.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 0 20:25:48.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 2.4/1.4, max unsent 0, version err 0, merge 0 20:26:49.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:27:50.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 0 20:28:51.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:29:52.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 1 20:30:53.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:31:54.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 0 20:32:55.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:33:56.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:34:57.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:35:58.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:36:48.79 Activating screen: pause_menu 20:36:48.80 GAME -- SimulationController::Pause 1 20:36:49.37 GAME -- SimulationController::Pause 1 20:36:49.54 GAME -- SimulationController::Pause 0 20:36:50.35 Activating screen: pause_menu 20:36:50.35 GAME -- SimulationController::Pause 1 20:36:50.59 GAME -- SimulationController::Pause 1 20:36:50.70 GAME -- SimulationController::Pause 0 20:36:51.20 Activating screen: pause_menu 20:36:51.20 GAME -- SimulationController::Pause 1 20:36:51.41 GAME -- SimulationController::Pause 1 20:36:51.58 GAME -- SimulationController::Pause 0 20:36:56.26 Activating screen: pause_menu 20:36:56.26 GAME -- SimulationController::Pause 1 20:36:56.54 GAME -- SimulationController::Pause 1 20:36:56.66 GAME -- SimulationController::Pause 0 20:36:57.46 Activating screen: pause_menu 20:36:57.46 GAME -- SimulationController::Pause 1 20:36:57.81 GAME -- SimulationController::Pause 1 20:36:57.92 GAME -- SimulationController::Pause 0 20:36:59.00 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.4, max unsent 0, version err 0, merge 0 20:36:59.10 Activating screen: pause_menu 20:36:59.10 GAME -- SimulationController::Pause 1 20:36:59.40 GAME -- SimulationController::Pause 1 20:36:59.55 GAME -- SimulationController::Pause 0 20:37:00.03 Activating screen: pause_menu 20:37:00.03 GAME -- SimulationController::Pause 1 20:37:00.26 GAME -- SimulationController::Pause 1 20:37:00.39 GAME -- SimulationController::Pause 0 20:37:00.84 Activating screen: pause_menu 20:37:00.84 GAME -- SimulationController::Pause 1 20:37:01.08 GAME -- SimulationController::Pause 1 20:37:01.19 GAME -- SimulationController::Pause 0 20:37:01.64 Activating screen: pause_menu 20:37:01.64 GAME -- SimulationController::Pause 1 20:37:01.87 GAME -- SimulationController::Pause 1 20:37:02.00 GAME -- SimulationController::Pause 0 20:37:02.41 Activating screen: pause_menu 20:37:02.41 GAME -- SimulationController::Pause 1 20:37:02.65 GAME -- SimulationController::Pause 1 20:37:02.76 GAME -- SimulationController::Pause 0 20:37:02.87 Activating screen: pause_menu 20:37:02.87 GAME -- SimulationController::Pause 1 20:37:03.31 GAME -- SimulationController::Pause 1 20:37:03.40 GAME -- SimulationController::Pause 0 20:37:03.76 Activating screen: pause_menu 20:37:03.76 GAME -- SimulationController::Pause 1 20:37:04.06 GAME -- SimulationController::Pause 1 20:37:04.21 GAME -- SimulationController::Pause 0 20:37:04.90 Activating screen: pause_menu 20:37:04.90 GAME -- SimulationController::Pause 1 20:37:05.15 GAME -- SimulationController::Pause 1 20:37:05.32 GAME -- SimulationController::Pause 0 20:37:05.77 Activating screen: pause_menu 20:37:05.77 GAME -- SimulationController::Pause 1 20:37:06.01 GAME -- SimulationController::Pause 1 20:37:06.20 GAME -- SimulationController::Pause 0 20:37:06.90 Activating screen: pause_menu 20:37:06.90 GAME -- SimulationController::Pause 1 20:37:07.19 GAME -- SimulationController::Pause 1 20:37:07.35 GAME -- SimulationController::Pause 0 20:37:07.79 Activating screen: pause_menu 20:37:07.80 GAME -- SimulationController::Pause 1 20:37:08.04 GAME -- SimulationController::Pause 1 20:37:08.19 GAME -- SimulationController::Pause 0 20:37:08.50 Activating screen: pause_menu 20:37:08.50 GAME -- SimulationController::Pause 1 20:37:08.77 GAME -- SimulationController::Pause 1 20:37:08.93 GAME -- SimulationController::Pause 0 20:37:09.36 Activating screen: pause_menu 20:37:09.36 GAME -- SimulationController::Pause 1 20:37:09.63 GAME -- SimulationController::Pause 1 20:37:09.78 GAME -- SimulationController::Pause 0 20:37:10.24 Activating screen: pause_menu 20:37:10.24 GAME -- SimulationController::Pause 1 20:37:10.55 GAME -- SimulationController::Pause 1 20:37:10.76 GAME -- SimulationController::Pause 0 20:37:11.15 Activating screen: pause_menu 20:37:11.15 GAME -- SimulationController::Pause 1 20:37:11.49 GAME -- SimulationController::Pause 1 20:37:11.65 GAME -- SimulationController::Pause 0 20:37:12.09 Activating screen: pause_menu 20:37:12.09 GAME -- SimulationController::Pause 1 20:37:12.32 GAME -- SimulationController::Pause 1 20:37:12.46 GAME -- SimulationController::Pause 0 20:37:12.51 Activating screen: pause_menu 20:37:12.51 GAME -- SimulationController::Pause 1 20:37:12.91 GAME -- SimulationController::Pause 1 20:37:13.09 GAME -- SimulationController::Pause 0 20:37:13.60 Activating screen: pause_menu 20:37:13.60 GAME -- SimulationController::Pause 1 20:37:13.95 GAME -- SimulationController::Pause 1 20:37:14.06 GAME -- SimulationController::Pause 0 20:37:14.45 Activating screen: pause_menu 20:37:14.45 GAME -- SimulationController::Pause 1 20:37:14.71 GAME -- SimulationController::Pause 1 20:37:14.83 GAME -- SimulationController::Pause 0 20:37:15.18 Activating screen: pause_menu 20:37:15.18 GAME -- SimulationController::Pause 1 20:37:15.51 GAME -- SimulationController::Pause 1 20:37:15.62 GAME -- SimulationController::Pause 0 20:37:16.60 Activating screen: pause_menu 20:37:16.60 GAME -- SimulationController::Pause 1 20:37:16.96 GAME -- SimulationController::Pause 1 20:37:17.12 GAME -- SimulationController::Pause 0 20:37:17.82 Activating screen: pause_menu 20:37:17.82 GAME -- SimulationController::Pause 1 20:37:18.17 GAME -- SimulationController::Pause 1 20:37:18.30 GAME -- SimulationController::Pause 0 20:37:19.03 Activating screen: pause_menu 20:37:19.03 GAME -- SimulationController::Pause 1 20:37:19.39 GAME -- SimulationController::Pause 1 20:37:19.52 GAME -- SimulationController::Pause 0 20:37:20.03 Activating screen: pause_menu 20:37:20.03 GAME -- SimulationController::Pause 1 20:37:20.33 GAME -- SimulationController::Pause 1 20:37:20.51 GAME -- SimulationController::Pause 0 20:37:20.86 Activating screen: pause_menu 20:37:20.86 GAME -- SimulationController::Pause 1 20:37:21.20 GAME -- SimulationController::Pause 1 20:37:21.38 GAME -- SimulationController::Pause 0 20:37:21.52 Activating screen: pause_menu 20:37:21.52 GAME -- SimulationController::Pause 1 20:37:21.89 GAME -- SimulationController::Pause 1 20:37:22.04 GAME -- SimulationController::Pause 0 20:37:22.60 Activating screen: pause_menu 20:37:22.60 GAME -- SimulationController::Pause 1 20:37:22.98 GAME -- SimulationController::Pause 1 20:37:23.09 GAME -- SimulationController::Pause 0 20:37:23.28 Activating screen: pause_menu 20:37:23.28 GAME -- SimulationController::Pause 1 20:37:23.59 GAME -- SimulationController::Pause 1 20:37:23.70 GAME -- SimulationController::Pause 0 20:37:24.43 Activating screen: pause_menu 20:37:24.44 GAME -- SimulationController::Pause 1 20:37:24.67 GAME -- SimulationController::Pause 1 20:37:24.81 GAME -- SimulationController::Pause 0 20:37:24.87 Activating screen: pause_menu 20:37:24.88 GAME -- SimulationController::Pause 1 20:37:25.09 GAME -- SimulationController::Pause 1 20:37:25.21 GAME -- SimulationController::Pause 0 20:37:25.30 Activating screen: pause_menu 20:37:25.30 GAME -- SimulationController::Pause 1 20:37:25.51 GAME -- SimulationController::Pause 1 20:37:25.62 GAME -- SimulationController::Pause 0 20:37:25.69 Activating screen: pause_menu 20:37:25.69 GAME -- SimulationController::Pause 1 20:37:25.87 GAME -- SimulationController::Pause 1 20:37:25.95 GAME -- SimulationController::Pause 0 20:37:26.06 Activating screen: pause_menu 20:37:26.06 GAME -- SimulationController::Pause 1 20:37:26.25 GAME -- SimulationController::Pause 1 20:37:26.41 Activating screen: pause_menu 20:37:26.41 GAME -- SimulationController::Pause 0 20:37:26.58 GAME -- SimulationController::Pause 1 20:37:26.61 GAME -- SimulationController::Pause 0 20:37:26.76 Activating screen: pause_menu 20:37:26.77 GAME -- SimulationController::Pause 1 20:37:26.95 GAME -- SimulationController::Pause 1 20:37:27.14 Activating screen: pause_menu 20:37:27.16 GAME -- SimulationController::Pause 0 20:37:27.33 GAME -- SimulationController::Pause 1 20:37:27.38 GAME -- SimulationController::Pause 0 20:37:27.52 Activating screen: pause_menu 20:37:27.52 GAME -- SimulationController::Pause 1 20:37:27.74 GAME -- SimulationController::Pause 1 20:37:27.91 GAME -- SimulationController::Pause 0 20:37:28.03 Activating screen: pause_menu 20:37:28.03 GAME -- SimulationController::Pause 1 20:37:28.23 GAME -- SimulationController::Pause 1 20:37:28.41 Activating screen: pause_menu 20:37:28.41 GAME -- SimulationController::Pause 0 20:37:28.58 GAME -- SimulationController::Pause 1 20:37:28.75 Activating screen: pause_menu 20:37:28.76 GAME -- SimulationController::Pause 0 20:37:29.02 GAME -- SimulationController::Pause 1 20:37:29.21 Activating screen: pause_menu 20:37:29.23 GAME -- SimulationController::Pause 0 20:37:29.40 GAME -- SimulationController::Pause 1 20:37:29.59 Activating screen: pause_menu 20:37:29.60 GAME -- SimulationController::Pause 0 20:37:30.03 GAME -- SimulationController::Pause 1 20:37:44.12 Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background' 20:38:00.00 Transport - median kBPS [hi/cur] sent = 0.1/0.1, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:38:13.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:14.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:15.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:16.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:17.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:18.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:19.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:20.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:21.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:22.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:23.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:24.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:25.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:26.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:27.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:28.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:29.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:30.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:31.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:32.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:33.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:34.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:35.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:36.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:37.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:38.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:39.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:40.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:41.19 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:42.20 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:43.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:44.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:45.33 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:46.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:47.31 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:48.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:49.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:50.32 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:51.46 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:52.45 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:53.46 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:54.45 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:55.44 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:56.44 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:57.56 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:58.58 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:38:59.57 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:00.57 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:01.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/0.0, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:39:01.57 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:02.57 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:03.57 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:04.56 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:05.70 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:06.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:07.70 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:08.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:09.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:10.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:11.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:12.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:13.71 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:14.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:15.70 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:16.70 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:17.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:18.70 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:19.69 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:20.37 Activating screen: pause_menu 20:39:20.38 GAME -- SimulationController::Pause 0 20:39:29.05 Activating screen: SaveGamePopup 20:39:37.22 Saving Terrain.... 20:39:37.76 HeightManager::Save took 0.541689 seconds 20:39:37.78 TChunkManagerIO::Save took 0.018288 seconds 20:39:37.78 GRASSMANAGER -- Save() called with grass disabled. Data lost. 20:39:37.78 GrassManager::Save took 1.30861e-005 seconds 20:39:37.93 TerrainTextureLoad::Save took 0.147831 seconds 20:39:37.93 DecalManager::Save took 0.000400025 seconds 20:39:37.93 TerrainMaterial::Save took 0.000164218 seconds 20:39:37.93 DeformationMask::Save took 0.000162935 seconds 20:39:37.93 ShellManager::Save took 4.61864e-006 seconds 20:39:37.93 LitterManager::Save took 1.41125e-005 seconds 20:39:37.93 WeatherManager::Save took 1.33427e-005 seconds 20:39:38.55 Pathfinder::Save... 20:39:39.07 Pathfinder::Save Done 20:39:42.89 GAME -- SimulationController::Pause 1 20:39:43.21 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:44.22 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:45.22 An ability failed to cast because target validation failed: abilities\axis_terror_bombardment 20:39:46.11 Activating screen: pause_menu 20:39:46.11 GAME -- SimulationController::Pause 0 20:39:48.68 Activating screen: prompt_generic 20:39:49.75 MOD -- Player KMARTIS-PC (network abort) (frame 15156) (KillPlayer) 20:39:49.75 GameApp::ReportStats 20:39:49.75 ReportStats - untracked game, no need to report match 4294967295:4294967295 20:39:49.75 ReportSimStats - nothing to report, no connection to the server 20:39:49.75 ReportStats - ReportSimStats failed 20:39:49.75 GameObj::DoGameOverPopup 20:39:49.75 Activating screen: GameOverDialog 20:39:49.75 GAME -- SimulationController::Pause 1 20:39:49.75 ArbitrationMessageNeeded 0 allies vs 0 enemies result 0 20:39:49.75 GameOverDialog - normal game ending (no sync errors) 20:39:51.04 Activating screen: postgame_stats_skirm 20:39:52.92 Activating screen: AppRestartingForm 20:39:52.92 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 20:39:52.92 Widget attempting to remove a non-existance listener. Widget:bkg_COH 20:39:52.92 Widget attempting to remove a non-existance listener. Widget:bkg_CXP1 20:39:52.92 Widget attempting to remove a non-existance listener. Widget:bkg_CXP2 20:39:52.92 Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1 20:39:52.92 Widget attempting to remove a non-existance listener. Widget:bkg_ToV 20:39:52.92 APP -- Game Stop 20:39:52.92 GameApp::TransitionGameStop 20:39:52.92 GameApp::TransitionQuitCommon 20:39:52.92 PerformanceRecorder: Updating profile history 20:39:52.92 GetMaxFrameTimeFromProfile: players=8 expected FPS=48.192768, bars=5, max avg=0.021, sd=0.000, 1 samples = 0.02 20:39:52.92 PerformanceRecorder::EndRecording - game size=8, max average=0.020750, worst frame=0.020750 20:39:52.92 Recording: No [8 players] 20:39:52.92 Total render, sim time: 2.28, 2.87 sec 20:39:52.92 Total render, sim frames: 513, 0 20:39:52.92 Max/Avg: 0.02, 0.01 sec (fps=48.19, 116.64) (102 samples) 20:39:52.92 Bars: 5 20:39:52.92 Profile Max Performance[8 players] = 0.02 0.00 0.00 0.00 0.00 20:39:52.92 Profile Avg Performance[8 players] = 0.01 0.00 0.00 0.00 0.00 20:39:52.92 GameObj::ShutdownGameObj 20:39:52.92 GAME -- Ending mission (Game over) 20:39:52.93 GameObjLoader 083c6688 - Unload 20:39:52.93 GameObjLoader 083c6688 - resetting counters 20:39:52.97 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 20:39:52.97 MOD -- Game Over at frame 15156 20:39:53.05 GameObjLoader 083c6528 - Unload 20:39:53.05 GameObjLoader 083c6528 - resetting counters 20:39:53.09 SOUND -- Shutting down ... 20:39:53.18 SOUND -- Shutdown completed! 20:39:53.18 MessageInternal::DestroyChannel: Destroyed channel 474d4343 20:39:53.18 GAME -- Beginning FE 20:39:53.18 Sent message game CompanyOfHeroes allowtraffic 20:39:53.18 UIFrontEnd - Loading Front End 20:39:53.18 THREAD: Hyper-Threading Technology Processors are not detected. 20:39:53.20 SOUND -- Initializing ... 20:39:53.22 SOUND -- Initialization completed! 20:39:53.22 UIFrontEnd - Initializing Forms 20:39:54.11 CampaignFilter::BindFilterSpecificWidgets() 20:39:54.14 Turning off auto-selection of profiles, we already have a profile selected. 20:39:54.14 MOD -- Mounting Eastern_Front_DLC1, 1.0 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC1\Movies\' 20:39:54.14 MOD -- Mounting Eastern_Front_DLC2, 1.0 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC2\Movies\' 20:39:54.14 MOD -- Mounting Eastern_Front_DLC3, 1.0 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\DataArtHigh\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\DataSoundEnglish\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\DLC3\Movies\' 20:39:54.14 MOD -- Mounting Eastern_Front_OPS, 1.0 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Ops\Data\' 20:39:54.14 MOD -- Mounting Eastern_Front, 1.0 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\RelicOnline\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Engine\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'WW2\Data\' 20:39:54.14 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\WW2\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtHigh\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\DataArtHigh\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataSoundHigh\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataSound\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\' 20:39:54.15 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Engine\Locale\English\Data\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\DataSoundEnglish\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\CoH\Locale\English\Data\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\DataAttrib\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Eastern_Front\Movies\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\Engine\Movies\' 20:39:54.17 FILESYSTEM -- filepath failure, path does not exist 'C:\Users\KMartis\Documents\My Games\Company of Heroes\WW2\Movies\' 20:39:54.18 Activating screen: AppRestartingForm 20:39:54.18 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 20:39:54.18 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 20:39:54.73 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 20:39:54.73 CRC & Version Info : 0000025a:703d9745:ec3c9e0d eastern_front:602:factionfix.dll 1 20:39:54.73 Activating screen: FEMovie 20:39:54.73 Activating screen: OnlineWidget 20:39:54.73 Activating screen: OnlineSelectGame 20:39:54.77 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 20:39:54.77 Activating screen: RelicOnlineChat 20:39:54.77 Activating screen: RelicOnlineNewsScreen 20:39:54.77 Activating screen: RelicOnlineStatsScreen 20:39:54.77 Activating screen: Achievements 20:39:54.77 GAME -- Setting campaign state to 'coh' 20:39:54.77 GAME -- Closing state 'coh' 20:39:54.77 GAME -- Setting campaign state to 'cxp2' 20:39:54.77 GAME -- Closing state 'cxp2' 20:39:54.77 GAME -- Setting campaign state to 'cxp1' 20:39:54.77 GAME -- Closing state 'cxp1' 20:39:54.77 GAME -- Setting campaign state to 'dlc1' 20:39:54.77 GAME -- Closing state 'dlc1' 20:39:54.77 GAME -- Setting campaign state to 'dlc2' 20:39:54.77 GAME -- Closing state 'dlc2' 20:39:54.77 GAME -- Setting campaign state to 'dlc3' 20:39:54.77 GAME -- Closing state 'dlc3' 20:39:54.77 GAME -- Setting campaign state to 'dlc1' 20:39:54.77 GAME -- Closing state 'dlc1' 20:39:54.78 Activating screen: GameHistory 20:39:54.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:54.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:54.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:54.78 Activating screen: OnlineSelectGame 20:39:54.78 Activating screen: RelicOnlineTabs 20:39:54.78 Activating screen: ScreenTipPopup 20:39:54.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:54.78 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:54.79 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:39:59.10 Activating screen: OnlineGameCreate 20:39:59.10 Activating screen: RelicOnlineTabs 20:40:01.78 Activating screen: MessageBoxPopup 20:40:01.78 Created Matchinfo 20:40:01.78 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 20:40:01.79 starting LAN hosting 20:40:01.79 OnJoinAdvertisementSuccess - joined online match, server leave notification required 20:40:01.79 starting local hosting 20:40:01.79 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:40:01.79 Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112; 20:40:01.79 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:40:01.79 Session::Host sid = FAA490E4A0E2AEE8, hostURL = , local addresses = WINaddr:[IP address redacted]:6112; 20:40:01.79 ValidateCustomData: called with 205 bytes of custom data 20:40:01.79 Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112; 20:40:01.79 AdvertisementInternal::Process - EVENT_NEWPEER 20:40:01.79 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 20:40:01.81 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 20:40:01.81 hosting - Session is connected 20:40:01.81 Net::Session::SetVisible - session is set to VISIBLE. 20:40:01.81 hosting completed successfully 20:40:01.81 HostAsync - completed with HostResult = 0 20:40:01.81 Activating screen: OnlineGameSetup 20:40:01.81 Activating screen: RelicOnlineTabs 20:40:01.81 AutomatchInternal::OnHostComplete - Completed Host with success=1 20:40:01.81 AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring 20:40:01.81 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 20:40:01.81 MessageInternal::CreateChannel: Created channel 534d5347 20:40:01.81 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 20:40:01.82 Net::Session::SetVisible - session is set to VISIBLE. 20:40:01.82 GetMaxFrameTimeFromProfile: players=6 expected FPS=71.967613, bars=5, max avg=0.014, sd=0.000, 1 samples = 0.01 20:40:01.82 ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1 20:40:01.82 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:01.83 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 20:40:01.83 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 20:40:01.83 GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH 20:40:01.83 Net::Session::SetVisible - session is set to VISIBLE. 20:40:01.83 GetMaxFrameTimeFromProfile: players=6 expected FPS=71.967613, bars=5, max avg=0.014, sd=0.000, 1 samples = 0.01 20:40:01.85 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:01.86 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:02.01 Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 8.5/1.1, #p/sec[s/r] = 2.3/1.3, max unsent 0, version err 0, merge 0 20:40:06.79 Activating screen: MessageBoxPopup 20:40:06.79 Session is marking the local peer PeerID 1 for death 20:40:06.79 Session::GetEvent DestroyPeer success Peer 1 removed 20:40:06.79 Session::DestroyPeer - deallocating peer 1 20:40:06.79 Session - Requested Disconnect for connectionID 6 20:40:06.79 Deallocated route ID=7 for PeerID 1 at WINaddr:[IP address redacted]:6112; 20:40:06.79 Session - Requested Disconnect for connectionID 7 20:40:06.79 Closed route 7<>7 for PeerID 1 at WINaddr:[IP address redacted]:6112; 20:40:06.79 AdvertisementInternal::Process - EVENT_DESTROYPEER 20:40:06.79 Destroyed Matchinfo 20:40:06.79 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 20:40:06.79 PeerRemoveAll - flushing local session peer data 20:40:06.79 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 20:40:06.79 Activating screen: FEMovie 20:40:06.79 Activating screen: OnlineWidget 20:40:06.79 Activating screen: FE_mm_01 20:40:06.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:06.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:06.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:40:06.80 MessageInternal::DestroyChannel: Destroyed channel 534d5347 20:40:06.80 GAME -- Setting campaign state to 'coh' 20:40:06.80 GAME -- Closing state 'coh' 20:40:06.80 GAME -- Setting campaign state to 'cxp2' 20:40:06.80 GAME -- Closing state 'cxp2' 20:40:06.80 GAME -- Setting campaign state to 'cxp1' 20:40:06.80 GAME -- Closing state 'cxp1' 20:40:06.80 GAME -- Setting campaign state to 'dlc1' 20:40:06.80 GAME -- Closing state 'dlc1' 20:40:06.80 GAME -- Setting campaign state to 'dlc2' 20:40:06.80 GAME -- Closing state 'dlc2' 20:40:06.80 GAME -- Setting campaign state to 'dlc3' 20:40:06.80 GAME -- Closing state 'dlc3' 20:40:08.26 Activating screen: QuitPromptDialog 20:40:09.35 GameApp::Quit( exitValue = 0 ) 20:40:09.35 GameApp::ShutFrontEnd 20:40:09.36 GAME -- Ending FE 20:40:09.36 UIFrontEnd - Unloading Front End 20:40:09.37 SOUND -- Shutting down ... 20:40:09.42 SOUND -- Shutdown completed! 20:40:09.44 ~FEAppListener - Flushing queued online notifications 20:40:09.52 DLLDriverLinker -- 2 DLL drivers found. 20:40:09.52 ActionDatabase : [2620] global actions, [92] dummy actions. 20:40:09.53 NetworkManager::Destroy - destroying network manager 20:40:09.53 MessageInternal::DestroyChannel: Destroyed channel 51434b4d 20:40:09.53 MessageInternal::DestroyChannel: Destroyed channel 50525459 20:40:09.53 PartyInternal: Fade to black 20:40:09.53 AutomatchInternal: Fade to black 20:40:09.53 PeerRemoveAll - flushing local session peer data 20:40:09.53 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 20:40:09.53 MessageInternal::DestroyChannel: Destroyed channel 47535450 20:40:09.53 Session - Requested Disconnect for connectionID 1 20:40:09.53 SessionManager::UnregisterSession - Unregistering session 0868cf00 20:40:09.53 SessionManager::~SessionManager - uninitializing 20:40:09.53 Net::ThreadFunction - Returning from the network thread function... 20:40:09.53 Transport::~Transport - shutting down transport 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:40:09.53 Wrote 292 bytes to network datastore login_cache.bin 20:40:09.53 Wrote 52 bytes to network datastore global_cache.bin 20:40:09.53 Wrote 28 bytes to network datastore ping_cache.bin 20:40:09.53 Datastore -- uninitialize complete 20:40:09.53 QuazalInitializer - static uninit of Quazal library 20:40:09.53 QuazalInitializer - unhooking memory 0 currently allocated, 564 allocations, total of 62968 bytes