-- Log file for all dbTracef messages -- 13:48:10.08 NetworkManager::Create - creating network manager 13:48:10.08 Read 850 bytes from network datastore login_cache.bin 13:48:10.08 Read 116568 bytes from network datastore global_cache.bin 13:48:10.08 Read 216550 bytes from network datastore ping_cache.bin 13:48:10.08 QuazalInitializer - static initializing Quazal library 13:48:10.09 PingCache - populating cache with 4491 pings 13:48:10.09 Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header 13:48:10.09 WinTransport - CreateSocket exclusive broadcast socket was available. 13:48:10.09 WinTransport - CreateSocket listening for broadcasts on default port 13:48:10.12 WinTransport - Host Name: TheBeast, aliases: , type=AF_INET, len=4 13:48:10.12 WinTransport - Host IP Address #0: [IP address redacted] 13:48:10.12 WinTransport - Interface #0: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_MULTICAST 13:48:10.12 WinTransport - Interface #1: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST 13:48:10.12 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 13:48:10.12 WinTransport - Quazal address string = udp:/address=[IP address redacted];port=6112 13:48:10.12 SessionManager - Peer Header Size = 16 bytes 13:48:10.12 SessionManager - Game Data overhead = 7 bytes 13:48:10.12 SessionManager - Proxy overhead = 7 bytes 13:48:10.12 MessageInternal::CreateChannel: Created channel 47535450 13:48:10.12 Session::Initialize - info, initializing session object, using threads. 13:48:10.12 SessionManager::RegisterSession - Registering new session 00bdf740 13:48:10.12 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 13:48:10.12 AutomatchInternal: Instantiating 13:48:10.12 PartyInternal: Instantiating 13:48:10.12 MessageInternal::CreateChannel: Created channel 50525459 13:48:10.12 MessageInternal::CreateChannel: Created channel 51434b4d 13:48:10.12 Net::ThreadFunction - Entering network thread function... 13:48:10.12 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 13:48:10.12 MessageInternal::CreateChannel: Created channel 534d5347 13:48:10.12 MessageInternal::CreateChannel: Created channel 474d4343 13:48:10.12 MessageInternal::CreateChannel: Created channel 474f424a 13:48:10.12 MessageInternal::CreateChannel: Created channel 4d4f444d 13:48:10.12 MessageInternal::CreateChannel: Created channel 53594e43 13:48:10.12 MessageInternal::DestroyChannel: Destroyed channel 534d5347 13:48:10.12 MessageInternal::DestroyChannel: Destroyed channel 474d4343 13:48:10.12 MessageInternal::DestroyChannel: Destroyed channel 474f424a 13:48:10.12 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 13:48:10.12 MessageInternal::DestroyChannel: Destroyed channel 53594e43 13:48:10.12 GAME -- Available memory: 12278MB Physical RAM, 12277MB Pagefile, 4095 Virtual Address Space 13:48:10.14 Transport - Largest received is now 19 13:48:10.14 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 13:48:18.48 DLLDriverLinker -- Adding driver 'spDx10.dll'. 13:48:18.49 DLLDriverLinker -- Adding driver 'spDx9.dll'. 13:48:18.49 DLLDriverLinker -- 2 DLL drivers found. 13:48:18.77 SPDx10 -- Adapter [ATI Radeon HD 5900 Series]: 1004MB dedicated video memory, 0MB dedicated system memory and 2803MB shared system memory. 13:48:20.15 DLLDriverLinker -- 2 DLL drivers found. 13:48:20.52 SPOOGE - Driver[DirectX10 Rendering Device] version[4,36] 13:48:20.52 GAME -- Resolution set to 1920x1080 (fullscreen). 13:48:20.55 SPDx10 -- Adapter Description = ATI Radeon HD 5900 Series 13:48:20.55 SPDx10 -- Driver Vendor = 0x00001002 Device = 0x0000689c SubSys = 0x25421002 Rev = 0x00000000 13:48:20.55 SPDx10 -- Driver Version Product = 0x0008 Version = 0x0011 SubVersion = 0x00 Build = 0.24 13:48:20.55 SPDx10 -- Driver LUID = 0x00000000-0x0000a3eb 13:48:20.55 SPDx10 -- 1004MB dedicated video memory, 0MB dedicated system memory and 2803MB shared system memory available. 13:48:20.55 ShaderDatabase: using shader profile [ps40] 13:48:22.27 SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 1024. 13:48:22.68 SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 1024. 13:48:22.70 FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua' 13:48:23.32 GameObjLoader 0aafd400 - resetting counters 13:48:23.32 GameObjLoader 0aafd400 - Created loader 13:48:23.32 GameObjLoader 0aafd560 - resetting counters 13:48:23.32 GameObjLoader 0aafd560 - Created loader 13:48:23.81 GAME -- Beginning FE 13:48:23.81 Sent message game CompanyOfHeroes started 7584 602 allowtraffic 13:48:23.81 RemoteDLManager - Connection Restored. 13:48:23.81 UIFrontEnd - Loading Front End 13:48:23.81 THREAD: Hyper-Threading Technology Processors are not detected. 13:48:23.86 SOUND -- Initializing ... 13:48:24.00 INNIMapDCA Key not found: sp_speechducker::time 13:48:25.47 SOUND -- Initialization completed! 13:48:25.47 UIFrontEnd - Initializing Forms 13:48:29.33 CampaignFilter::BindFilterSpecificWidgets() 13:48:29.47 Activating screen: AppLoadingForm 13:48:29.47 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 13:48:29.47 Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3] 13:48:31.12 GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh] 13:48:31.12 GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1] 13:48:31.13 GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2] 13:48:31.14 GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3] 13:48:31.14 GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2] 13:48:31.15 GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1] 13:48:31.70 GAME -- Using player profile BOBPHEONI 13:48:35.49 Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase. 13:48:36.72 Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase. 13:48:38.82 QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260 13:48:38.82 RendezvousManager: CreateSession - starting profile=Guest login 13:48:39.63 RendezvousManager: Login complete and successfull 13:48:39.63 RendezvousManager initialized 13:48:39.72 Current server English:live version is 602.0, client is 602.0 13:48:39.72 OnConnect: successful connection established, enabling reconnect 13:48:39.72 OnConnect: this wasnt a reconnect, no need for autologin 13:48:39.72 Logging in sirbadassI on controller:0 13:48:40.01 Login completed: ACCOUNT_VALIDATED 13:48:40.01 Found 1 profiles for account sirbadassI 13:48:40.01 Found profile: SirBadassI 13:48:40.01 installed_products = ( CXP1 COH DLC1 DLC2 DLC3 ) 13:48:40.01 OnLogin: no previous login, auto selecting profile not required 13:48:40.01 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 13:48:40.02 CRC & Version Info : 0000025a:9166edaf:ec3c9e0d eastern_front:602:factionfix.dll 1 13:48:40.02 Activating screen: FEMovie 13:48:40.02 Activating screen: OnlineWidget 13:48:40.02 Activating screen: RelicOnlineProfileSelect 13:48:40.02 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 13:48:40.02 Activating screen: RelicOnlineWait 13:48:40.02 RendezvousManager - destroying chat handler 13:48:40.02 RendezvousManager: CreateSession - starting logout profile = 100:Guest 13:48:40.15 RendezvousManager: Logout complete 13:48:40.15 RendezvousManager - terminating all server calls in progress 13:48:40.15 CallManager - terminating all server calls in progress (1 in progress) 13:48:40.15 RendezvousManager: OnCredentialsEvent - starting profile login 13:48:40.83 RendezvousManager: Login complete and successfull 13:48:40.83 RendezvousManager - creating chat handler 13:48:40.83 RendezvousManager::CreateNATTraversalClient - NAT traversal available. 13:48:40.92 QuazalSelectProfileAsync - Got UserID 13:48:40.93 Transport - Largest sent is now 16 13:48:41.01 Transport - Largest received is now 42 13:48:41.10 GetUserStats requested stats for PIDs ( 3232261 ) (best:0, full:1) 13:48:41.18 SelectProfileAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=6112;PID=3232261;RVCID=58953367], private [udp:/address=[IP address redacted];port=6112;PID=3232261] 13:48:41.28 QuazalSelectProfileAsync - Got Full Stats 13:48:41.37 GetAutomatchMaps: Got [34] maps 13:48:41.39 PopulateArmyListBox - skipping race 2 13:48:41.39 PopulateArmyListBox - skipping race 0 13:48:41.39 PopulateArmyListBox - skipping race 1 13:48:41.39 PopulateArmyListBox - skipping race 3 13:48:41.39 AutoMatchForm::OnArmySelectionChanged - sending request info 13:48:41.39 GetMaxFrameTimeFromProfile: players=2 expected FPS=22.133688, bars=5, max avg=0.045, sd=0.000, 1 samples = 0.05 13:48:41.39 AutoMatchForm::OnMatchTypeSelectionChanged - sending team info 13:48:41.39 QuazalSelectProfileAsync - Got Automatch maps 13:48:41.99 QuazalSelectProfileAsync - GetFriends result - CacheState = 1 13:48:42.08 Profile [00000000:00315205] selected on controller#0 13:48:42.08 Activating screen: FEMovie 13:48:42.08 Activating screen: OnlineWidget 13:48:42.08 Activating screen: FE_mm_01 13:48:42.08 Activating screen: RelicOnlineWait 13:48:42.10 GAME -- Setting campaign state to 'coh' 13:48:42.10 GAME -- Closing state 'coh' 13:48:42.11 GAME -- Setting campaign state to 'cxp2' 13:48:42.11 GAME -- Closing state 'cxp2' 13:48:42.11 GAME -- Setting campaign state to 'cxp1' 13:48:42.11 GAME -- Closing state 'cxp1' 13:48:42.12 GAME -- Setting campaign state to 'dlc1' 13:48:42.12 GAME -- Closing state 'dlc1' 13:48:42.12 GAME -- Setting campaign state to 'dlc2' 13:48:42.12 GAME -- Closing state 'dlc2' 13:48:42.13 GAME -- Setting campaign state to 'dlc3' 13:48:42.13 GAME -- Closing state 'dlc3' 13:48:45.23 UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01 13:48:45.23 Activating screen: RelicOnlineCampaign 13:48:45.24 GAME -- Setting campaign state to 'dlc1' 13:48:45.29 Activating screen: RelicOnlineChat 13:48:45.29 Activating screen: RelicOnlineNewsScreen 13:48:45.35 Activating screen: RelicOnlineStatsScreen 13:48:45.36 Activating screen: Achievements 13:48:45.36 GAME -- Closing state 'dlc1' 13:48:45.36 GAME -- Setting campaign state to 'coh' 13:48:45.36 GAME -- Closing state 'coh' 13:48:45.36 GAME -- Setting campaign state to 'cxp2' 13:48:45.36 GAME -- Closing state 'cxp2' 13:48:45.37 GAME -- Setting campaign state to 'cxp1' 13:48:45.37 GAME -- Closing state 'cxp1' 13:48:45.37 GAME -- Setting campaign state to 'dlc1' 13:48:45.37 GAME -- Closing state 'dlc1' 13:48:45.38 GAME -- Setting campaign state to 'dlc2' 13:48:45.38 GAME -- Closing state 'dlc2' 13:48:45.38 GAME -- Setting campaign state to 'dlc3' 13:48:45.38 GAME -- Closing state 'dlc3' 13:48:45.39 GAME -- Setting campaign state to 'dlc1' 13:48:45.39 Activating screen: GameHistory 13:48:45.39 GAME -- Closing state 'dlc1' 13:48:45.40 GAME -- Setting campaign state to 'coh' 13:48:45.40 GAME -- Closing state 'coh' 13:48:45.40 GAME -- Setting campaign state to 'cxp2' 13:48:45.40 GAME -- Closing state 'cxp2' 13:48:45.41 GAME -- Setting campaign state to 'cxp1' 13:48:45.41 GAME -- Closing state 'cxp1' 13:48:45.41 GAME -- Setting campaign state to 'dlc1' 13:48:45.41 GAME -- Closing state 'dlc1' 13:48:45.42 GAME -- Setting campaign state to 'dlc2' 13:48:45.42 GAME -- Closing state 'dlc2' 13:48:45.42 GAME -- Setting campaign state to 'dlc3' 13:48:45.42 GAME -- Closing state 'dlc3' 13:48:45.43 GAME -- Setting campaign state to 'dlc1' 13:48:45.43 Activating screen: RelicOnlineCampaign 13:48:45.43 Activating screen: RelicOnlineTabs 13:48:45.43 GAME -- Closing state 'dlc1' 13:48:45.43 GAME -- Setting campaign state to 'coh' 13:48:45.43 GAME -- Closing state 'coh' 13:48:45.44 GAME -- Setting campaign state to 'cxp2' 13:48:45.44 GAME -- Closing state 'cxp2' 13:48:45.44 GAME -- Setting campaign state to 'cxp1' 13:48:45.44 GAME -- Closing state 'cxp1' 13:48:45.45 GAME -- Setting campaign state to 'dlc1' 13:48:45.45 GAME -- Closing state 'dlc1' 13:48:45.45 GAME -- Setting campaign state to 'dlc2' 13:48:45.45 GAME -- Closing state 'dlc2' 13:48:45.46 GAME -- Setting campaign state to 'dlc3' 13:48:45.46 GAME -- Closing state 'dlc3' 13:48:45.46 GAME -- Setting campaign state to 'dlc1' 13:48:46.32 Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 801 13:48:46.32 Sent message game CompanyOfHeroes softthrottle 13:48:46.32 Got dlman msg [ack game CompanyOfHeroes softthrottle] 13:48:46.48 CancelAutoMatching - called 13:48:46.48 Activating screen: OnlineSelectGame 13:48:46.48 Activating screen: RelicOnlineTabs 13:48:46.48 Activating screen: FEMovie 13:48:46.48 Activating screen: OnlineWidget 13:48:46.48 Activating screen: FE_mm_01 13:48:46.48 GAME -- Closing state 'dlc1' 13:48:46.48 GAME -- Setting campaign state to 'coh' 13:48:46.48 GAME -- Closing state 'coh' 13:48:46.49 GAME -- Setting campaign state to 'cxp2' 13:48:46.49 GAME -- Closing state 'cxp2' 13:48:46.49 GAME -- Setting campaign state to 'cxp1' 13:48:46.49 GAME -- Closing state 'cxp1' 13:48:46.50 GAME -- Setting campaign state to 'dlc1' 13:48:46.50 GAME -- Closing state 'dlc1' 13:48:46.50 GAME -- Setting campaign state to 'dlc2' 13:48:46.50 GAME -- Closing state 'dlc2' 13:48:46.51 GAME -- Setting campaign state to 'dlc3' 13:48:46.51 GAME -- Closing state 'dlc3' 13:48:46.51 GAME -- Setting campaign state to 'dlc1' 13:48:46.52 GAME -- Closing state 'dlc1' 13:48:46.53 GAME -- Setting campaign state to 'coh' 13:48:46.53 GAME -- Closing state 'coh' 13:48:46.53 GAME -- Setting campaign state to 'cxp2' 13:48:46.53 GAME -- Closing state 'cxp2' 13:48:46.54 GAME -- Setting campaign state to 'cxp1' 13:48:46.54 GAME -- Closing state 'cxp1' 13:48:46.54 GAME -- Setting campaign state to 'dlc1' 13:48:46.54 GAME -- Closing state 'dlc1' 13:48:46.55 GAME -- Setting campaign state to 'dlc2' 13:48:46.55 GAME -- Closing state 'dlc2' 13:48:46.55 GAME -- Setting campaign state to 'dlc3' 13:48:46.55 GAME -- Closing state 'dlc3' 13:48:46.56 QueryMatches: Got [59] maps, [165] ids, [17] advertisements, startID [1] 13:48:46.56 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 148 matches 13:48:46.75 GetUserStats requested stats for PIDs ( 2200333 3214421 3217150 3582638 ) (best:4, full:0) 13:48:48.28 Activating screen: MessageBoxPopup 13:48:48.28 Created Matchinfo 13:48:48.28 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 13:48:48.28 starting online hosting 13:48:48.29 OnlineHostAsync: initiating CallCreateMatch 13:48:48.39 OnlineHostAsync: created gid=191029677 13:48:48.39 RendezvousNotifier - Received Participate ParticipationEvent. 13:48:48.40 Transport - Largest sent is now 79 13:48:48.48 Transport - Largest received is now 105 13:48:48.65 OnlineHostAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=6112;PID=3232261;RVCID=58953367], private [udp:/address=[IP address redacted];port=6112;PID=3232261] 13:48:48.75 OnlineHostAsync: initiating UpdateSessionURL [gid=191029677, url=udp:/address=[IP address redacted];port=6112;PID=3232261;RVCID=58953367] 13:48:48.86 OnJoinAdvertisementSuccess - joined online match, server leave notification required 13:48:48.86 starting local hosting 13:48:48.86 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 13:48:48.86 Allocated route ID=0 for PeerID 1 at WINaddr:[IP address redacted]:6112; 13:48:48.86 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 13:48:48.86 Session::Host sid = B62E1AD, hostURL = , local addresses = WINaddr:[IP address redacted]:6112; 13:48:48.86 ValidateCustomData: called with 439 bytes of custom data 13:48:48.86 Host accepted Peer 1 into the match at address list=WINaddr:[IP address redacted]:6112;, routes=WINaddr:[IP address redacted]:6112; 13:48:48.86 AdvertisementInternal::Process - EVENT_NEWPEER 13:48:48.86 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 13:48:48.87 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 13:48:48.87 hosting - Session is connected 13:48:48.87 Net::Session::SetVisible - session is set to INVISIBLE. 13:48:48.87 hosting completed successfully 13:48:48.87 HostAsync - completed with HostResult = 0 13:48:48.87 UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01 13:48:48.87 Activating screen: OnlineGameSetup 13:48:48.87 MessageInternal::CreateChannel: Created channel 534d5347 13:48:48.87 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 13:48:48.87 MatchInternal::SetMatchType - new type 14 - updating server 13:48:48.88 SetVisible called while !IsConnected 13:48:48.88 GetMaxFrameTimeFromProfile: players=4 expected FPS=29.415359, bars=5, max avg=0.030, sd=0.004, 5 samples = 0.03 0.04 0.03 0.03 0.03 13:48:48.89 Activating screen: RelicOnlineChat 13:48:48.89 Activating screen: RelicOnlineNewsScreen 13:48:48.89 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 13:48:48.89 MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0 13:48:48.92 Activating screen: RelicOnlineStatsScreen 13:48:48.92 Activating screen: Achievements 13:48:48.92 GAME -- Setting campaign state to 'coh' 13:48:48.92 GAME -- Closing state 'coh' 13:48:48.92 GAME -- Setting campaign state to 'cxp2' 13:48:48.92 GAME -- Closing state 'cxp2' 13:48:48.93 GAME -- Setting campaign state to 'cxp1' 13:48:48.93 GAME -- Closing state 'cxp1' 13:48:48.93 GAME -- Setting campaign state to 'dlc1' 13:48:48.93 GAME -- Closing state 'dlc1' 13:48:48.94 GAME -- Setting campaign state to 'dlc2' 13:48:48.94 GAME -- Closing state 'dlc2' 13:48:48.94 GAME -- Setting campaign state to 'dlc3' 13:48:48.94 GAME -- Closing state 'dlc3' 13:48:48.94 GAME -- Setting campaign state to 'dlc1' 13:48:48.94 GAME -- Closing state 'dlc1' 13:48:48.95 Activating screen: GameHistory 13:48:48.95 GAME -- Setting campaign state to 'coh' 13:48:48.95 GAME -- Closing state 'coh' 13:48:48.96 GAME -- Setting campaign state to 'cxp2' 13:48:48.96 GAME -- Closing state 'cxp2' 13:48:48.96 GAME -- Setting campaign state to 'cxp1' 13:48:48.97 GAME -- Closing state 'cxp1' 13:48:48.97 GAME -- Setting campaign state to 'dlc1' 13:48:48.97 GAME -- Closing state 'dlc1' 13:48:48.97 GAME -- Setting campaign state to 'dlc2' 13:48:48.98 GAME -- Closing state 'dlc2' 13:48:48.98 GAME -- Setting campaign state to 'dlc3' 13:48:48.98 GAME -- Closing state 'dlc3' 13:48:48.98 Activating screen: OnlineGameSetup 13:48:48.98 Activating screen: RelicOnlineTabs 13:48:48.98 AutomatchInternal::OnHostComplete - Completed Host with success=1 13:48:48.98 AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring 13:48:48.98 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 13:48:48.99 GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH 13:48:48.99 Net::Session::SetVisible - session is set to INVISIBLE. 13:48:48.99 GetMaxFrameTimeFromProfile: players=4 expected FPS=29.415359, bars=5, max avg=0.030, sd=0.004, 5 samples = 0.03 0.04 0.03 0.03 0.03 13:48:49.08 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:49.09 UpdateMatch: Call to UpdateGathering started, matchTypeID = 14. 13:48:49.09 Sending encoded message 1512 bytes, to UserID [00000000:0021930d] 13:48:49.15 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:49.87 Activating screen: GameHistory 13:48:49.87 Activating screen: RelicOnlineTabs 13:48:50.45 QueryMatches: Got [59] maps, [165] ids, [17] advertisements, startID [191026430] 13:48:50.45 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 131 matches 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.35 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.35 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:51.36 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:52.37 Activating screen: FriendsListPopup 13:48:55.44 QueryMatches: Got [59] maps, [168] ids, [17] advertisements, startID [191027704] 13:48:55.44 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 117 matches 13:48:57.13 Transport - Largest sent is now 80 13:48:57.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:57.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:48:57.77 Activating screen: MessageBoxPopup 13:48:57.77 Session is marking the local peer PeerID 1 for death 13:48:57.77 OnlineLeaveAdvertisementAsync: Call to LeaveMatch id = 191029677, cancel = 1, badPids = 0, matchState = 0 13:48:57.77 Session::GetEvent DestroyPeer success Peer 1 removed 13:48:57.77 Session::DestroyPeer - deallocating peer 1 13:48:57.77 Session - Requested Disconnect for connectionID 2 13:48:57.77 Deallocated route ID=3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 13:48:57.77 Session - Requested Disconnect for connectionID 3 13:48:57.77 Closed route 3<>3 for PeerID 1 at WINaddr:[IP address redacted]:6112; 13:48:57.77 AdvertisementInternal::Process - EVENT_DESTROYPEER 13:48:57.77 LeaveMatchInternal - no action taken, needLeave=0, service=0 13:48:57.77 Destroyed Matchinfo 13:48:57.77 APP -- Game Load 13:48:57.77 GameApp::TransitionGameLoad 13:48:57.77 Sent message game CompanyOfHeroes allowtraffic 13:48:57.78 GAME -- Setting campaign state to 'coh' 13:48:57.78 GAME -- Closing state 'coh' 13:48:57.78 GAME -- Setting campaign state to 'cxp2' 13:48:57.78 GAME -- Closing state 'cxp2' 13:48:57.79 GAME -- Setting campaign state to 'cxp1' 13:48:57.79 GAME -- Closing state 'cxp1' 13:48:57.79 GAME -- Setting campaign state to 'dlc1' 13:48:57.79 GAME -- Closing state 'dlc1' 13:48:57.80 GAME -- Setting campaign state to 'dlc2' 13:48:57.80 GAME -- Closing state 'dlc2' 13:48:57.80 GAME -- Setting campaign state to 'dlc3' 13:48:57.80 GAME -- Closing state 'dlc3' 13:48:57.81 MessageInternal::DestroyChannel: Destroyed channel 534d5347 13:48:57.81 GAME -- Ending FE 13:48:57.81 UIFrontEnd - Unloading Front End 13:48:57.82 SOUND -- Shutting down ... 13:48:57.88 SOUND -- Shutdown completed! 13:48:57.90 GAME -- Beginning FE 13:48:57.90 Sent message game CompanyOfHeroes allowtraffic 13:48:57.90 UIFrontEnd - Loading Front End 13:48:57.90 THREAD: Hyper-Threading Technology Processors are not detected. 13:48:57.93 SOUND -- Initializing ... 13:48:59.66 SOUND -- Initialization completed! 13:48:59.66 UIFrontEnd - Initializing Forms 13:49:03.63 CampaignFilter::BindFilterSpecificWidgets() 13:49:03.80 Turning off auto-selection of profiles, we already have a profile selected. 13:49:03.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:49:03.80 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:49:03.80 MOD - Setting player (0) race to: allies_soviets 13:49:03.80 MOD - Setting player (0) race to: 2 13:49:03.80 MOD - Setting player (1) race to: allies 13:49:03.80 MOD - Setting player (1) race to: 1 13:49:03.80 MOD - Setting player (2) race to: axis 13:49:03.80 MOD - Setting player (2) race to: 3 13:49:03.80 MOD - Setting player (3) race to: axis 13:49:03.80 MOD - Setting player (3) race to: 3 13:49:03.80 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 13:49:03.80 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 13:49:03.81 APP -- Game Start 13:49:03.81 Sent message game CompanyOfHeroes allowtraffic 13:49:03.81 GAME -- Ending FE 13:49:03.81 UIFrontEnd - Unloading Front End 13:49:03.82 SOUND -- Shutting down ... 13:49:03.88 SOUND -- Shutdown completed! 13:49:03.89 13:49:03.89 GAME -- *** Beginning mission 4p_achelous river (1 Humans, 3 Computers) *** 13:49:03.89 13:49:03.89 GAME -- Recording game 13:49:04.02 Activating screen: GameLoadScreen 13:49:04.03 Got dlman msg [ack game CompanyOfHeroes allowtraffic] 13:49:04.18 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 13:49:04.18 PeerRemoveAll - flushing local session peer data 13:49:04.18 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 13:49:04.50 QueryMatches: Got [61] maps, [169] ids, [17] advertisements, startID [191028296] 13:49:04.50 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 102 matches 13:49:04.50 THREAD: Hyper-Threading Technology Processors are not detected. 13:49:04.53 SOUND -- Initializing ... 13:49:06.27 SOUND -- Initialization completed! 13:49:06.30 PHYSICS: detected processor(s) capable of handling 8 threads. 13:49:06.34 MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\4p_achelous river\4p_achelous river' 13:49:06.34 MOD -- Using Mod 'Eastern_Front' 13:49:06.35 Unable to load/parse precache file [DATA:scenarios\mp\classic\4p_achelous river\4p_achelous river_precache.lua] 13:49:06.45 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 13:49:06.45 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 13:49:11.01 Transport - median kBPS [hi/cur] sent = 0.2/0.0, recvd = 0.2/0.0, #p/sec[s/r] = 0.1/0.3, max unsent 0, version err 0, merge 0 13:49:15.56 GameObjLoader - upgrading load_count from 0 to 1070 13:49:16.19 QueryMatches: Got [57] maps, [167] ids, [17] advertisements, startID [191028708] 13:49:16.19 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 84 matches 13:49:20.91 QueryMatches: Got [58] maps, [168] ids, [17] advertisements, startID [191029171] 13:49:20.91 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 69 matches 13:49:21.42 GameObjLoader - upgrading load_count from 1070 to 1746 13:49:21.63 PHYSICS -- Created node factory 'HVOK' 13:49:21.63 PHYSICS -- Created node factory 'DMMY' 13:49:26.10 QueryMatches: Got [56] maps, [169] ids, [17] advertisements, startID [191029333] 13:49:26.10 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 55 matches 13:49:30.84 QueryMatches: Got [56] maps, [170] ids, [17] advertisements, startID [191029522] 13:49:30.84 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 41 matches 13:49:35.66 QueryMatches: Got [56] maps, [174] ids, [17] advertisements, startID [191029621] 13:49:35.66 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches 13:49:35.87 GameObjLoader - upgrading load_count from 32 to 4126 13:49:38.77 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_lower_load.smf'. Expensive operation 13:49:40.64 QueryMatches: Got [56] maps, [172] ids, [17] advertisements, startID [191029695] 13:49:40.65 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches 13:49:44.60 GameObjLoader 0aafd400 - resetting counters 13:49:44.60 GameObjLoader 0aafd400 - LOAD_DONE 13:49:44.60 GAME - SessionSetup 13:49:45.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:49:45.34 GameInfo::ResetInfo - SyncLevel set to 0 on reset 13:49:46.02 CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP. 13:49:46.03 TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048 13:49:46.03 TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024 13:49:53.72 AnimatorVisualFactory -- Loaded a non-orthonormal matrix! Model: [art\models\races\allies\projectiles\60mm_mortar] -- (normal: 0, orthogonal: 0) 13:49:55.62 SAVEGAME -- Read delayed load chunk (119976 bytes) 13:49:57.03 GAME - CreateGEWorld in 12430 ms 13:49:57.04 TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed. 13:49:57.56 GAME - SessionSetup finished in 12961 ms 13:49:57.56 GAME - WaterReflectionManagerSetup 13:49:57.56 GAME - WaterReflectionManagerSetup finished in 0 ms 13:49:58.21 MessageInternal::CreateChannel: Created channel 474d4343 13:49:58.21 QueryMatches: Got [52] maps, [168] ids, [17] advertisements, startID [191029746] 13:49:58.22 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 10 matches 13:49:58.24 Regenerating ImpassMap data... 13:49:58.24 Impass Data was already valid, but regenerating... 13:49:58.33 Generating CanBuild Map. THIS SHOULD ONLY HAPPEN IN WORLDBUILDER! IF YOU SEE THIS IN GAME, RE-SAVE THE MAP! 13:49:58.33 Regenerating CanBuildMap data... 13:49:58.33 Generating CanShoot Map. 13:49:58.33 Pathfinder::Regenerate()... 13:49:58.36 Generating PathSectorMap... 13:49:58.54 Pathfinder::Regenerate() Done. [Cope FactionFix] - Trying to hook function [Cope FactionFix] - Got LuaState @0x07df2210 [Cope FactionFix] - patched @0x62e85491 [Cope FactionFix] - hooked @0x62e85492 [Cope FactionFix] - patched @0x62e84a07 [Cope FactionFix] - hooked @0x62e84a07 [Cope FactionFix] - Hook installed 13:49:58.83 ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded. 13:50:04.31 MessageInternal::CreateChannel: Created channel 4d4f444d 13:50:05.64 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 13:50:05.64 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 13:50:05.64 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 13:50:05.64 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 13:50:05.64 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 13:50:06.07 SPEECHMANAGER -- Loaded in 0.357087 seconds 13:50:07.72 QueryMatches: Got [55] maps, [175] ids, [17] advertisements, startID [191029807] 13:50:07.73 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 6 matches 13:50:07.88 GameObjLoader - upgrading load_count from 0 to 377 13:50:09.67 QueryMatches: Got [55] maps, [175] ids, [17] advertisements, startID [191029869] 13:50:09.68 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 2 matches 13:50:09.98 GameObjLoader 0aafd560 - resetting counters 13:50:09.98 GameObjLoader 0aafd560 - LOAD_DONE 13:50:11.74 PreloadResources took 0ms. 13:50:11.80 GAME -- Loading completed (68 seconds) 13:50:11.80 SIM -- Setting SyncErrorChecking level to Low 13:50:12.00 Transport - median kBPS [hi/cur] sent = 0.4/0.2, recvd = 0.3/0.1, #p/sec[s/r] = 0.6/0.7, max unsent 0, version err 0, merge 0 13:50:14.40 QueryMatches: Got [57] maps, [175] ids, [17] advertisements, startID [191025888] 13:50:14.40 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 5 matches 13:50:16.60 Activating screen: GameScreen 13:50:16.60 Activating screen: Decorators_widescreen 13:50:16.60 Activating screen: Taskbar_widescreen 13:50:16.60 Activating screen: SubtitleScreen 13:50:16.60 Activating screen: TextOverlayScreen 13:50:16.70 PerformanceRecorder::StartRecording for game size 4 13:50:16.70 GAME -- Starting mission... 13:50:20.91 MOD -- Player CPU - Normal set to AI Type: AI Player (frame 3098) (CmdAI) 13:50:20.91 MOD -- Player CPU - Easy set to AI Type: AI Player (frame 3098) (CmdAI) 13:50:20.91 MOD -- Player CPU - Easy set to AI Type: AI Player (frame 3098) (CmdAI) 13:50:21.42 QueryMatches: Got [58] maps, [171] ids, [17] advertisements, startID [191027763] 13:50:21.42 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 7 matches 13:50:25.44 QueryMatches: Got [54] maps, [172] ids, [17] advertisements, startID [191028404] 13:50:25.45 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 11 matches 13:50:26.90 Warning: binding repeat_1(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 13:50:27.77 Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12] 13:50:30.39 QueryMatches: Got [53] maps, [175] ids, [17] advertisements, startID [191029032] 13:50:30.39 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 18 matches 13:50:32.40 Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background' 13:50:35.41 QueryMatches: Got [55] maps, [174] ids, [17] advertisements, startID [191029329] 13:50:35.41 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches 13:50:40.37 QueryMatches: Got [51] maps, [167] ids, [17] advertisements, startID [191029589] 13:50:40.38 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches 13:50:45.32 QueryMatches: Got [52] maps, [168] ids, [17] advertisements, startID [191029710] 13:50:45.32 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches 13:50:50.34 QueryMatches: Got [52] maps, [171] ids, [17] advertisements, startID [191029793] 13:50:50.35 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 22 matches 13:50:55.35 QueryMatches: Got [54] maps, [168] ids, [17] advertisements, startID [191029867] 13:50:55.35 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 12 matches 13:51:00.40 QueryMatches: Got [51] maps, [156] ids, [17] advertisements, startID [191029957] 13:51:13.01 Transport - median kBPS [hi/cur] sent = 0.4/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 13:52:13.29 GAME -- SimulationController::Pause 0 13:52:13.31 GAME -- SimulationController::Pause 1 13:52:14.00 Transport - median kBPS [hi/cur] sent = 0.4/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 13:53:15.01 Transport - median kBPS [hi/cur] sent = 0.4/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0 13:53:40.52 Activating screen: pause_menu 13:53:40.52 GAME -- SimulationController::Pause 1 13:53:41.70 GAME -- SimulationController::Pause 1 13:53:43.13 GAME -- SimulationController::Pause 0 13:53:43.13 GAME -- SimulationController::Pause 1 13:53:49.55 Activating screen: pause_menu 13:53:49.55 GAME -- SimulationController::Pause 1 13:53:50.93 Activating screen: prompt_generic 13:53:51.95 MOD -- Player SirBadassI (network abort) (frame 4010) (KillPlayer) 13:53:51.96 GameApp::ReportStats 13:53:51.96 ReportStats - untracked game, no need to report match 4294967295:4294967295 13:53:51.96 ReportSimStats - storing simulation results for match 4294967295:4294967295 13:53:51.96 ReportStats - ReportSimStats failed 13:53:51.96 GameApp::Quit( exitValue = 0 ) 13:53:52.03 GAME -- SimulationController::Pause 1 13:53:52.03 Activating screen: AppRestartingForm 13:53:52.03 SetupProductLoadingArt - choosing bgArt = 4 (gold=0) 13:53:52.03 Widget attempting to remove a non-existance listener. Widget:bkg_COH 13:53:52.03 Widget attempting to remove a non-existance listener. Widget:bkg_CXP1 13:53:52.03 Widget attempting to remove a non-existance listener. Widget:bkg_CXP2 13:53:52.03 Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1 13:53:52.03 Widget attempting to remove a non-existance listener. Widget:bkg_ToV 13:53:52.07 GameApp::ShutFrontEnd 13:53:52.07 GameObj::ShutdownGameObj 13:53:52.07 GAME -- Ending mission (Game over) 13:53:52.07 GameObjLoader 0aafd560 - Unload 13:53:52.07 GameObjLoader 0aafd560 - resetting counters 13:53:52.10 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 13:53:52.10 MOD -- Game Over at frame 4010 13:53:52.20 GameObjLoader 0aafd400 - Unload 13:53:52.20 GameObjLoader 0aafd400 - resetting counters 13:53:52.24 SOUND -- Shutting down ... 13:53:52.31 SOUND -- Shutdown completed! 13:53:52.32 MessageInternal::DestroyChannel: Destroyed channel 474d4343 13:53:52.33 ~FEAppListener - Flushing queued online notifications 13:53:52.77 DLLDriverLinker -- 2 DLL drivers found. 13:53:52.78 ActionDatabase : [2344] global actions, [40] dummy actions. 13:53:52.78 NetworkManager::Destroy - destroying network manager 13:53:52.78 RendezvousManager::Shutdown - deleting instance 13:53:52.78 RendezvousManager - terminating all server calls in progress 13:53:52.78 CallManager - terminating all server calls in progress (0 in progress) 13:53:52.78 RendezvousManager - destroying chat handler 13:53:52.78 RendezvousManager::DestroyNATTraversalClient - NAT traversal no longer available. 13:53:52.78 ~RendezvousManager - disconnecting from server 13:53:52.90 MessageInternal::DestroyChannel: Destroyed channel 51434b4d 13:53:52.90 MessageInternal::DestroyChannel: Destroyed channel 50525459 13:53:52.90 PartyInternal: Fade to black 13:53:52.90 AutomatchInternal: Fade to black 13:53:52.90 PeerRemoveAll - flushing local session peer data 13:53:52.90 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 13:53:52.90 MessageInternal::DestroyChannel: Destroyed channel 47535450 13:53:52.90 Session - Requested Disconnect for connectionID 1 13:53:52.90 SessionManager::UnregisterSession - Unregistering session 00bdf740 13:53:52.90 SessionManager::~SessionManager - uninitializing 13:53:52.91 Net::ThreadFunction - Returning from the network thread function... 13:53:52.91 Transport::~Transport - shutting down transport 13:53:52.91 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 13:53:52.91 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 13:53:52.92 Wrote 850 bytes to network datastore login_cache.bin 13:53:52.92 Wrote 116568 bytes to network datastore global_cache.bin 13:53:52.93 Wrote 81136 bytes to network datastore ping_cache.bin 13:53:52.93 Datastore -- uninitialize complete 13:53:52.93 QuazalInitializer - static uninit of Quazal library 13:53:52.93 QuazalInitializer - unhooking memory 0 currently allocated, 47687 allocations, total of 15362816 bytes