-- Log file for all dbTracef messages -- 14:23:08.29 NetworkManager::Create - creating network manager 14:23:08.29 Read 510 bytes from network datastore login_cache.bin 14:23:08.29 Read 52 bytes from network datastore global_cache.bin 14:23:08.29 Read 28 bytes from network datastore ping_cache.bin 14:23:08.29 QuazalInitializer - static initializing Quazal library 14:23:08.29 PingCache - populating cache with 0 pings 14:23:08.43 Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header 14:23:08.58 WinTransport - CreateSocket exclusive broadcast socket was available. 14:23:08.58 WinTransport - CreateSocket listening for broadcasts on default port 14:23:08.91 WinTransport - Host Name: USER-PC, aliases: , type=AF_INET, len=4 14:23:08.91 WinTransport - Host IP Address #0: [IP address redacted] 14:23:08.91 WinTransport - Interface #0: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_POINTTOPOINT 14:23:08.91 WinTransport - Interface #1: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST 14:23:08.91 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 14:23:08.91 WinTransport - Quazal address string = udp:/address=[IP address redacted];port=6112 14:23:08.91 SessionManager - Peer Header Size = 16 bytes 14:23:08.91 SessionManager - Game Data overhead = 7 bytes 14:23:08.91 SessionManager - Proxy overhead = 7 bytes 14:23:08.91 MessageInternal::CreateChannel: Created channel 47535450 14:23:08.91 Session::Initialize - info, initializing session object, using threads. 14:23:08.91 SessionManager::RegisterSession - Registering new session 0fd12e40 14:23:08.91 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 14:23:08.91 AutomatchInternal: Instantiating 14:23:08.91 PartyInternal: Instantiating 14:23:08.91 MessageInternal::CreateChannel: Created channel 50525459 14:23:08.91 MessageInternal::CreateChannel: Created channel 51434b4d 14:23:08.91 Net::ThreadFunction - Entering network thread function... 14:23:08.93 Transport - Largest received is now 19 14:23:08.93 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 14:23:09.09 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 14:23:09.09 MessageInternal::CreateChannel: Created channel 534d5347 14:23:09.09 MessageInternal::CreateChannel: Created channel 474d4343 14:23:09.09 MessageInternal::CreateChannel: Created channel 474f424a 14:23:09.09 MessageInternal::CreateChannel: Created channel 4d4f444d 14:23:09.09 MessageInternal::CreateChannel: Created channel 53594e43 14:23:09.09 MessageInternal::DestroyChannel: Destroyed channel 534d5347 14:23:09.09 MessageInternal::DestroyChannel: Destroyed channel 474d4343 14:23:09.09 MessageInternal::DestroyChannel: Destroyed channel 474f424a 14:23:09.09 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 14:23:09.09 MessageInternal::DestroyChannel: Destroyed channel 53594e43 14:23:09.10 GAME -- Available memory: 4095MB Physical RAM, 4093MB Pagefile, 4095 Virtual Address Space 14:23:11.67 DLLDriverLinker -- Adding driver 'spDx10.dll'. 14:23:11.94 DLLDriverLinker -- Adding driver 'spDx9.dll'. 14:23:11.94 DLLDriverLinker -- 2 DLL drivers found. 14:23:12.31 SPDx10 -- Adapter [NVIDIA GeForce GTS 450]: 993MB dedicated video memory, 0MB dedicated system memory and 1791MB shared system memory. 14:23:16.92 DLLDriverLinker -- 2 DLL drivers found. 14:23:17.26 SPOOGE - Driver[DirectX10 Rendering Device] version[4,36] 14:23:17.26 GAME -- Resolution set to 1366x768 (fullscreen). 14:23:17.34 SPDx10 -- Adapter Description = NVIDIA GeForce GTS 450 14:23:17.34 SPDx10 -- Driver Vendor = 0x000010de Device = 0x00000dc4 SubSys = 0x23601462 Rev = 0x000000a1 14:23:17.34 SPDx10 -- Driver Version Product = 0x0008 Version = 0x0011 SubVersion = 0x00 Build = 259.22 14:23:17.34 SPDx10 -- Driver LUID = 0x00000000-0x00009774 14:23:17.34 SPDx10 -- 993MB dedicated video memory, 0MB dedicated system memory and 1791MB shared system memory available. 14:23:17.50 ShaderDatabase: using shader profile [ps40] 14:23:18.47 SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256. 14:23:18.69 SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256. 14:23:18.72 FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua' 14:23:19.94 GameObjLoader 0bb69be8 - resetting counters 14:23:19.94 GameObjLoader 0bb69be8 - Created loader 14:23:19.94 GameObjLoader 0b1eff00 - resetting counters 14:23:19.94 GameObjLoader 0b1eff00 - Created loader 14:23:20.68 GAME -- Beginning FE 14:23:20.68 Sent message game CompanyOfHeroes started 3624 601 allowtraffic 14:23:20.68 RemoteDLManager - Connection Restored. 14:23:20.68 UIFrontEnd - Loading Front End 14:23:20.68 THREAD: Hyper-Threading Technology Processors are not detected. 14:23:21.09 SOUND -- Initializing ... 14:23:21.16 INNIMapDCA Key not found: sp_speechducker::time 14:23:21.65 SOUND -- Initialization completed! 14:23:21.65 UIFrontEnd - Initializing Forms 14:23:23.44 CampaignFilter::BindFilterSpecificWidgets() 14:23:23.56 Activating screen: AppLoadingForm 14:23:23.56 SetupProductLoadingArt - choosing bgArt = 0 (gold=0) 14:23:23.56 Got dlman msg [dlmanager version 1.0 peertraffic 0 uploadlimit 2147483647 seedratio 3] 14:23:26.01 GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh] 14:23:26.02 GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1] 14:23:26.02 GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2] 14:23:26.03 GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3] 14:23:26.05 GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2] 14:23:26.05 GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1] 14:23:26.59 GAME -- Using player profile USER-PC 14:23:28.39 Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase. 14:23:29.34 Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase. 14:23:31.58 QuazalLoginService - *** Connecting to server: localhost:30260 14:23:31.58 RendezvousManager: CreateSession - starting profile=Guest login 14:23:46.60 GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure. 14:23:46.60 RendezvousManager - unable to connect to the server as guest 14:23:46.60 OnConnect: connect failure 14:23:46.60 RendezvousManager::Shutdown - deleting instance 14:23:46.60 RendezvousManager - terminating all server calls in progress 14:23:46.60 CallManager - terminating all server calls in progress (1 in progress) 14:23:46.60 RendezvousManager - destroying chat handler 14:23:46.60 ~RendezvousManager - m_state = 7, no need for server disconnect 14:23:46.62 OnConnect: this wasnt a reconnect, no need for autologin 14:23:46.62 SetupProductLoadingArt - choosing bgArt = 0 (gold=0) 14:23:46.62 CRC & Version Info : 00000259:a1073ad9:48d2c5df eastern_front:601:factionfix.dll 1 14:23:46.62 Activating screen: FEMovie 14:23:46.62 Activating screen: OnlineWidget 14:23:46.62 Activating screen: RelicOnlineLogin 14:23:46.71 SetupProductLoadingArt - choosing bgArt = 0 (gold=0) 14:23:46.71 You have a valid version of the game. 14:23:46.78 AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now 14:23:46.78 installed_products = ( DLC1 DLC2 DLC3 COH CXP1 ) 14:23:46.78 OnLogin: no previous login, auto selecting profile not required 14:23:46.78 Activating screen: MessageBoxPopup 14:23:46.78 Activating screen: MessageBoxPopup 14:23:46.78 ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1 14:24:09.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 0.0/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:24:43.33 Beginning media verification... 14:24:43.33 Validator type : validators\rs\rs.dll 14:24:43.41 Activating screen: MessageBoxPopup 14:24:55.65 Activating screen: FE_mm_01 14:24:55.66 GAME -- Setting campaign state to 'coh' 14:24:55.66 GAME -- Closing state 'coh' 14:24:55.66 GAME -- Setting campaign state to 'cxp2' 14:24:55.66 GAME -- Closing state 'cxp2' 14:24:55.66 GAME -- Setting campaign state to 'cxp1' 14:24:55.66 GAME -- Closing state 'cxp1' 14:24:55.66 GAME -- Setting campaign state to 'dlc1' 14:24:55.66 GAME -- Closing state 'dlc1' 14:24:55.66 GAME -- Setting campaign state to 'dlc2' 14:24:55.66 GAME -- Closing state 'dlc2' 14:24:55.66 GAME -- Setting campaign state to 'dlc3' 14:24:55.66 GAME -- Closing state 'dlc3' 14:25:10.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 0.0/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:25:33.03 Activating screen: MessageBoxPopup 14:25:33.03 Created Matchinfo 14:25:33.03 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 14:25:33.03 starting LAN hosting 14:25:33.04 OnJoinAdvertisementSuccess - joined online match, server leave notification required 14:25:33.04 starting local hosting 14:25:33.04 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 14:25:33.04 Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112; 14:25:33.04 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 14:25:33.04 Session::Host sid = B3742D86979CF638, hostURL = , local addresses = WINaddr:[IP address redacted]:6112; 14:25:33.04 ValidateCustomData: called with 187 bytes of custom data 14:25:33.04 Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112; 14:25:33.04 AdvertisementInternal::Process - EVENT_NEWPEER 14:25:33.04 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 14:25:33.06 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 14:25:33.06 hosting - Session is connected 14:25:33.06 Net::Session::SetVisible - session is set to VISIBLE. 14:25:33.06 hosting completed successfully 14:25:33.06 HostAsync - completed with HostResult = 0 14:25:33.06 UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01 14:25:33.06 Activating screen: OnlineGameSetup 14:25:33.06 MessageInternal::CreateChannel: Created channel 534d5347 14:25:33.07 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:33.07 MatchInternal::SetMatchType - new type 14 - updating server 14:25:33.08 SetVisible called while !IsConnected 14:25:33.08 local host PeerID 1 CONN ack= 0 ( 0ms~0) unack= 0, retry= 0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=0ms) 100.00%, pending=0, dead=0 14:25:33.08 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 14:25:33.08 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:33.09 Activating screen: RelicOnlineChat 14:25:33.09 Activating screen: RelicOnlineNewsScreen 14:25:33.09 Activating screen: RelicOnlineStatsScreen 14:25:33.09 Activating screen: Achievements 14:25:33.09 Transport - Largest received is now 512 14:25:33.09 GAME -- Setting campaign state to 'dlc1' 14:25:33.09 GAME -- Closing state 'dlc1' 14:25:33.09 GAME -- Setting campaign state to 'coh' 14:25:33.09 GAME -- Closing state 'coh' 14:25:33.10 GAME -- Setting campaign state to 'cxp2' 14:25:33.10 GAME -- Closing state 'cxp2' 14:25:33.10 GAME -- Setting campaign state to 'cxp1' 14:25:33.10 GAME -- Closing state 'cxp1' 14:25:33.10 GAME -- Setting campaign state to 'dlc1' 14:25:33.10 GAME -- Closing state 'dlc1' 14:25:33.10 GAME -- Setting campaign state to 'dlc2' 14:25:33.10 GAME -- Closing state 'dlc2' 14:25:33.11 GAME -- Setting campaign state to 'dlc3' 14:25:33.11 GAME -- Closing state 'dlc3' 14:25:33.11 GAME -- Setting campaign state to 'dlc1' 14:25:33.11 GAME -- Closing state 'dlc1' 14:25:33.11 Transport - Largest received is now 1029 14:25:33.12 Activating screen: GameHistory 14:25:33.12 GAME -- Setting campaign state to 'coh' 14:25:33.12 GAME -- Closing state 'coh' 14:25:33.12 GAME -- Setting campaign state to 'cxp2' 14:25:33.12 GAME -- Closing state 'cxp2' 14:25:33.12 GAME -- Setting campaign state to 'cxp1' 14:25:33.12 GAME -- Closing state 'cxp1' 14:25:33.13 GAME -- Setting campaign state to 'dlc1' 14:25:33.13 GAME -- Closing state 'dlc1' 14:25:33.13 GAME -- Setting campaign state to 'dlc2' 14:25:33.13 GAME -- Closing state 'dlc2' 14:25:33.13 GAME -- Setting campaign state to 'dlc3' 14:25:33.13 GAME -- Closing state 'dlc3' 14:25:33.13 Activating screen: OnlineGameSetup 14:25:33.13 Activating screen: RelicOnlineTabs 14:25:33.13 AutomatchInternal::OnHostComplete - Completed Host with success=1 14:25:33.13 AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring 14:25:33.13 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 14:25:33.14 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:33.14 Net::Session::SetVisible - session is set to VISIBLE. 14:25:33.14 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:33.19 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:33.29 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:37.49 Activating screen: DynamicPopupMenu 14:25:38.32 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:38.32 Net::Session::SetVisible - session is set to VISIBLE. 14:25:38.32 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:38.33 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:38.37 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:38.95 Activating screen: DynamicPopupMenu 14:25:39.54 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:39.54 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:39.54 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:39.54 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:39.59 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:40.54 Activating screen: RaceSelectionPopup 14:25:41.18 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:41.18 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:41.18 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:41.20 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:41.24 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:41.60 Activating screen: RaceSelectionPopup 14:25:42.15 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:42.15 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:42.16 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:42.17 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:42.21 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:42.55 Activating screen: RaceSelectionPopup 14:25:43.07 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:43.07 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:43.07 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:43.08 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:43.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:44.45 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:44.45 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:44.45 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:44.46 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:44.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:45.63 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:45.63 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:45.63 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:45.67 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:45.71 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:47.38 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:47.38 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:47.38 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:47.38 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:47.42 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:51.15 Activating screen: DynamicPopupMenu 14:25:52.22 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:52.22 Net::Session::SetVisible - session is set to INVISIBLE. 14:25:52.22 GetMaxFrameTimeFromProfile: players=6 expected FPS=15.584197, bars=4, max avg=0.047, sd=0.017, 5 samples = 0.02 0.05 0.05 0.07 0.04 14:25:52.24 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:52.28 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:57.20 GameSetupForm - No win/loss reporting and only a single player, disconnecting 14:25:57.20 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 14:25:57.20 SetVisible called while !IsConnected 14:25:57.20 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:57.20 GameSetupForm - Starting game 14:25:57.20 GameInfo::ResetInfo - SyncLevel set to 0 on reset 14:25:57.20 PopulateGameInfo - random seed:[1311319557], guid:[{80858b25-acad-48e5-b759-aef74d50a977}], sync level:[2] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 Error loading [DATA:levelingCurve.lua] 14:25:57.21 MOD - Setting player (0) race to: allies_soviets 14:25:57.21 MOD - Setting player (0) race to: 2 14:25:57.21 MOD - Setting player (1) race to: allies_soviets 14:25:57.21 MOD - Setting player (1) race to: 2 14:25:57.21 MOD - Setting player (2) race to: allies_soviets 14:25:57.21 MOD - Setting player (2) race to: 2 14:25:57.21 MOD - Setting player (3) race to: axis 14:25:57.21 MOD - Setting player (3) race to: 3 14:25:57.21 MOD - Setting player (4) race to: axis 14:25:57.21 MOD - Setting player (4) race to: 3 14:25:57.21 MOD - Setting player (5) race to: axis 14:25:57.21 MOD - Setting player (5) race to: 3 14:25:57.21 Activating screen: MessageBoxPopup 14:25:57.21 Session is marking the local peer PeerID 1 for death 14:25:57.21 Session::GetEvent DestroyPeer success Peer 1 removed 14:25:57.21 Session::DestroyPeer - deallocating peer 1 14:25:57.21 Session - Requested Disconnect for connectionID 2 14:25:57.21 Deallocated route ID=3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 14:25:57.21 Session - Requested Disconnect for connectionID 3 14:25:57.21 Closed route 3<>3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 14:25:57.21 AdvertisementInternal::Process - EVENT_DESTROYPEER 14:25:57.21 Destroyed Matchinfo 14:25:57.23 Transport - Largest sent is now 8 14:25:57.23 APP -- Game Start 14:25:57.23 Sent message game CompanyOfHeroes allowtraffic 14:25:57.23 GAME -- Setting campaign state to 'coh' 14:25:57.23 GAME -- Closing state 'coh' 14:25:57.23 GAME -- Setting campaign state to 'cxp2' 14:25:57.23 GAME -- Closing state 'cxp2' 14:25:57.23 GAME -- Setting campaign state to 'cxp1' 14:25:57.23 GAME -- Closing state 'cxp1' 14:25:57.24 GAME -- Setting campaign state to 'dlc1' 14:25:57.24 GAME -- Closing state 'dlc1' 14:25:57.24 GAME -- Setting campaign state to 'dlc2' 14:25:57.24 GAME -- Closing state 'dlc2' 14:25:57.24 GAME -- Setting campaign state to 'dlc3' 14:25:57.24 GAME -- Closing state 'dlc3' 14:25:57.24 MessageInternal::DestroyChannel: Destroyed channel 534d5347 14:25:57.24 GAME -- Ending FE 14:25:57.24 UIFrontEnd - Unloading Front End 14:25:57.27 SOUND -- Shutting down ... 14:25:57.32 SOUND -- Shutdown completed! 14:25:57.35 14:25:57.35 GAME -- *** Beginning mission 6p_stalingrad (1 Humans, 5 Computers) *** 14:25:57.35 14:25:57.48 GAME -- Recording game 14:25:57.55 Activating screen: GameLoadScreen 14:25:57.55 ReportGameStartStats - no connection to server unable to report 14:25:57.55 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 14:25:57.55 PeerRemoveAll - flushing local session peer data 14:25:57.55 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 14:25:57.55 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 14:25:58.20 THREAD: Hyper-Threading Technology Processors are not detected. 14:25:58.36 SOUND -- Initializing ... 14:25:58.95 SOUND -- Initialization completed! 14:25:59.00 PHYSICS: detected processor(s) capable of handling 4 threads. 14:25:59.06 MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_stalingrad' 14:25:59.06 MOD -- Using Mod 'Eastern_Front' 14:25:59.08 Unable to load/parse precache file [DATA:scenarios\mp\6p_stalingrad_precache.lua] 14:25:59.11 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 14:25:59.12 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 14:26:01.79 GameObjLoader - upgrading load_count from 0 to 1243 14:26:05.77 GameObjLoader - upgrading load_count from 1243 to 2073 14:26:06.00 PHYSICS -- Created node factory 'HVOK' 14:26:06.00 PHYSICS -- Created node factory 'DMMY' 14:26:11.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:26:31.42 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 14:26:34.76 GameObjLoader - upgrading load_count from 39 to 5088 14:26:45.56 GameObjLoader 0bb69be8 - resetting counters 14:26:45.56 GameObjLoader 0bb69be8 - LOAD_DONE 14:26:45.56 GAME - SessionSetup 14:26:46.51 CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP. 14:26:46.51 TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048 14:26:46.51 TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024 14:26:52.44 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.44 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.44 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.45 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.46 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.46 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.51 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.51 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.65 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.65 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.68 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.69 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.69 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.69 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.69 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:52.69 Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX 14:26:58.93 GAME - CreateGEWorld in 13363 ms 14:26:58.94 TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed. 14:26:59.14 GAME - SessionSetup finished in 13578 ms 14:26:59.18 GAME - WaterReflectionManagerSetup 14:26:59.18 GAME - WaterReflectionManagerSetup finished in 0 ms 14:26:59.93 MessageInternal::CreateChannel: Created channel 474d4343 [Cope FactionFix] - Trying to hook function [Cope FactionFix] - Got LuaState @0x06584c88 [Cope FactionFix] - patched @0x6cee54c1 [Cope FactionFix] - hooked @0x6cee54c2 [Cope FactionFix] - patched @0x6cee4a37 [Cope FactionFix] - hooked @0x6cee4a37 [Cope FactionFix] - Hook installed 14:27:00.19 ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded. 14:27:05.61 Error: trying execute a Health command but the entity doesn't have a HealthExt 14:27:05.84 MOD -- Player (unused player) (frame 0) (KillPlayer) 14:27:05.84 MOD -- Player (unused player) (frame 0) (KillPlayer) 14:27:06.52 MessageInternal::CreateChannel: Created channel 4d4f444d 14:27:06.80 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 14:27:07.31 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 14:27:07.32 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 14:27:07.32 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 14:27:07.32 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 14:27:07.32 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 14:27:07.79 SPEECHMANAGER -- Loaded in 0.410879 seconds 14:27:09.85 GameObjLoader - upgrading load_count from 0 to 390 14:27:12.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:27:12.50 GameObjLoader 0b1eff00 - resetting counters 14:27:12.50 GameObjLoader 0b1eff00 - LOAD_DONE 14:27:13.03 PreloadResources took 0ms. 14:27:13.32 GAME -- Loading completed (76 seconds) 14:27:13.32 SIM -- Setting SyncErrorChecking level to None 14:27:21.33 Activating screen: GameScreen 14:27:21.33 Activating screen: Decorators_widescreen 14:27:21.33 Activating screen: Taskbar_widescreen 14:27:21.33 Activating screen: SubtitleScreen 14:27:21.33 Activating screen: TextOverlayScreen 14:27:21.46 PerformanceRecorder::StartRecording for game size 6 14:27:21.46 GAME -- Starting mission... 14:27:24.52 MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI) 14:27:24.52 MOD -- Player CPU - Hard set to AI Type: AI Player (frame 1) (CmdAI) 14:27:24.52 MOD -- Player CPU - Easy set to AI Type: AI Player (frame 1) (CmdAI) 14:27:24.52 MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI) 14:27:24.52 MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI) 14:27:30.99 Warning: non auto-match upgrade not found in AE, tuning 14:27:34.14 Activating screen: Command_Tree 14:27:34.69 Activating screen: Command_Branch 14:28:02.05 Warning: binding repeat_2(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:28:07.74 Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background' 14:28:13.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:28:47.56 Warning: binding repeat_3(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:29:02.21 Warning: binding repeat_1(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:29:06.64 Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:29:14.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:30:15.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:31:16.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:32:13.68 Activating screen: Command_Branch 14:32:17.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 14:33:18.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:34:19.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:35:20.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:35:28.71 Activating screen: Command_Branch 14:36:21.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:36:23.45 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:36:23.45 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:36:23.45 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. 14:36:44.70 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:36:44.70 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:36:44.70 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. 14:37:22.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:37:55.12 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:37:55.12 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:37:55.12 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. 14:37:59.16 Unable to bind updater for fx [fx_special\production_exhaust]. It could be looping in a fire-n-forget action. 14:37:59.16 Muting spew of bind updater. Fix art and re-test. 14:38:23.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:38:43.92 Was already stealing a skeleton when told to steal another [mortar_target]. 14:39:24.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 14:39:51.66 Activating screen: Command_Branch 14:39:53.33 Activating screen: NewObjective_widescreen 14:39:58.28 Activating screen: Command_Branch 14:40:25.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:41:26.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:42:27.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:43:28.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:44:29.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:44:43.34 Activating screen: Command_Branch 14:45:30.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 14:46:27.71 Activating screen: Command_Branch 14:46:31.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:47:32.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:48:01.79 Unabled to do upgrade, CANPRODUCE_NoResources 14:48:02.77 Unabled to do upgrade, CANPRODUCE_NoResources 14:48:33.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:49:34.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 14:49:39.01 Warning: binding repeat_4(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:50:35.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:51:36.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:52:37.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:53:26.15 Activating screen: Command_Branch 14:53:38.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:54:27.26 Unabled to do upgrade, CANPRODUCE_NoResources 14:54:28.21 Unabled to do upgrade, CANPRODUCE_NoResources 14:54:39.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:55:40.00 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:56:09.99 Warning: binding repeat_5(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 14:56:41.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:57:12.19 Activating screen: Command_Branch 14:57:42.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:57:42.54 SimulationController::GenerateSyncCmd - couldn't send all commands, 9 binned. 14:58:43.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 1.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 14:59:33.63 Activating screen: Command_Branch