-- Log file for all dbTracef messages -- 20:33:57.75 NetworkManager::Create - creating network manager 20:33:57.75 Read 870 bytes from network datastore login_cache.bin 20:33:57.75 Read 137946 bytes from network datastore global_cache.bin 20:33:57.76 Read 381124 bytes from network datastore ping_cache.bin 20:33:57.76 QuazalInitializer - static initializing Quazal library 20:33:57.76 PingCache - populating cache with 12028 pings 20:33:57.87 Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header 20:33:57.87 WinTransport - CreateSocket exclusive broadcast socket was available. 20:33:57.87 WinTransport - CreateSocket listening for broadcasts on default port 20:33:57.88 WinTransport - Host Name: Mattias-PC, aliases: , type=AF_INET, len=4 20:33:57.88 WinTransport - Host IP Address #0: [IP address redacted] 20:33:57.88 WinTransport - Interface #0: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_BROADCAST IFF_MULTICAST 20:33:57.88 WinTransport - Interface #1: ip:[IP address redacted], broadcast:[IP address redacted], flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST 20:33:57.88 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:33:57.88 WinTransport - Quazal address string = udp:/address=[IP address redacted];port=6112 20:33:57.88 SessionManager - Peer Header Size = 16 bytes 20:33:57.88 SessionManager - Game Data overhead = 7 bytes 20:33:57.88 SessionManager - Proxy overhead = 7 bytes 20:33:57.88 MessageInternal::CreateChannel: Created channel 47535450 20:33:57.88 Session::Initialize - info, initializing session object, using threads. 20:33:57.88 SessionManager::RegisterSession - Registering new session 0839f750 20:33:57.88 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:33:57.88 Net::ThreadFunction - Entering network thread function... 20:33:57.88 AutomatchInternal: Instantiating 20:33:57.88 PartyInternal: Instantiating 20:33:57.88 MessageInternal::CreateChannel: Created channel 50525459 20:33:57.88 MessageInternal::CreateChannel: Created channel 51434b4d 20:33:57.88 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 20:33:57.88 MessageInternal::CreateChannel: Created channel 534d5347 20:33:57.88 MessageInternal::CreateChannel: Created channel 474d4343 20:33:57.88 MessageInternal::CreateChannel: Created channel 474f424a 20:33:57.88 MessageInternal::CreateChannel: Created channel 4d4f444d 20:33:57.88 MessageInternal::CreateChannel: Created channel 53594e43 20:33:57.88 MessageInternal::DestroyChannel: Destroyed channel 534d5347 20:33:57.88 MessageInternal::DestroyChannel: Destroyed channel 474d4343 20:33:57.88 MessageInternal::DestroyChannel: Destroyed channel 474f424a 20:33:57.88 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 20:33:57.88 MessageInternal::DestroyChannel: Destroyed channel 53594e43 20:33:57.88 GAME -- Available memory: 16328MB Physical RAM, 16326MB Pagefile, 4095 Virtual Address Space 20:33:57.90 Transport - Largest received is now 19 20:33:57.90 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:34:00.39 DLLDriverLinker -- Adding driver 'spDx10.dll'. 20:34:00.42 DLLDriverLinker -- Adding driver 'spDx9.dll'. 20:34:00.42 DLLDriverLinker -- 2 DLL drivers found. 20:34:00.52 SPDx10 -- Adapter [AMD Radeon HD 7900 Series]: 3045MB dedicated video memory, 0MB dedicated system memory and 3812MB shared system memory. 20:34:02.03 DLLDriverLinker -- 2 DLL drivers found. 20:34:02.13 SPOOGE - Driver[DirectX9 rendering device] version[4,36] 20:34:02.13 GAME -- Resolution set to 1920x1080 (fullscreen). 20:34:02.15 SPDx9 -- Driver Name = aticfx32.dll Desc = AMD Radeon HD 7900 Series 20:34:02.15 SPDx9 -- Driver Vendor = 0x1002 Device = 0x679A SubSys = 0x254C1458 Rev = 0x0000 20:34:02.15 SPDx9 -- Driver Version Product = 0x0008 Version = 0x0011 SubVersion = 0x00 Build = 11.72 20:34:02.15 SPDx9 -- Driver GUID = {D7B71EE2-24DA-11CF-7C73-4605BEC2C535} 20:34:02.40 SPDx9 -- 2733MB available Texture Memory 20:34:02.40 ShaderDatabase: using shader profile [ps30] 20:34:02.92 FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua' 20:34:03.12 GameObjLoader 0aee0608 - resetting counters 20:34:03.12 GameObjLoader 0aee0608 - Created loader 20:34:03.12 GameObjLoader 0aee0768 - resetting counters 20:34:03.12 GameObjLoader 0aee0768 - Created loader 20:34:03.37 GAME -- Beginning FE 20:34:03.37 Sent message game CompanyOfHeroes started 4728 602 allowtraffic 20:34:03.37 RemoteDLManager - Connection Restored. 20:34:03.37 UIFrontEnd - Loading Front End 20:34:03.37 THREAD: Hyper-Threading Technology Processors are not detected. 20:34:03.50 SOUND -- Initializing ... 20:34:03.56 INNIMapDCA Key not found: sp_speechducker::time 20:34:04.02 SOUND -- Initialization completed! 20:34:04.02 UIFrontEnd - Initializing Forms 20:34:05.51 CampaignFilter::BindFilterSpecificWidgets() 20:34:05.62 Activating screen: MovieScreen 20:34:05.62 Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3] 20:34:30.21 Activating screen: AppLoadingForm 20:34:30.21 SetupProductLoadingArt - choosing bgArt = 1 (gold=0) 20:34:30.83 GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh] 20:34:30.83 GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1] 20:34:30.84 GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2] 20:34:30.84 GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3] 20:34:30.84 GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2] 20:34:30.84 GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1] 20:34:31.20 GAME -- Using player profile MATTIAS-PC 20:34:31.72 Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase. 20:34:31.89 Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase. 20:34:32.58 QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260 20:34:32.58 RendezvousManager: CreateSession - starting profile=Guest login 20:34:33.88 RendezvousManager: Login complete and successfull 20:34:33.88 RendezvousManager initialized 20:34:34.05 Current server English:live version is 602.0, client is 602.0 20:34:34.05 OnConnect: successful connection established, enabling reconnect 20:34:34.05 OnConnect: this wasnt a reconnect, no need for autologin 20:34:34.05 Logging in mSnowflake on controller:0 20:34:34.54 Login completed: ACCOUNT_VALIDATED 20:34:34.54 Found 2 profiles for account mSnowflake 20:34:34.54 Found profile: mSnowflake 20:34:34.54 Found profile: Elias321 20:34:34.54 installed_products = ( CXP1 COH DLC1 DLC2 DLC3 COH CXP1 ) 20:34:34.54 OnLogin: no previous login, auto selecting profile not required 20:34:34.54 SetupProductLoadingArt - choosing bgArt = 4 (gold=1) 20:34:34.55 CRC & Version Info : 0000025a:de0493a4:ec3c9e0d eastern_front:602:factionfix.dll 1 20:34:34.55 Activating screen: FEMovie 20:34:34.55 Activating screen: OnlineWidget 20:34:34.55 Activating screen: RelicOnlineProfileSelect 20:34:34.56 SetupProductLoadingArt - choosing bgArt = 4 (gold=1) 20:34:55.28 Activating screen: RelicOnlineWait 20:34:55.28 RendezvousManager - destroying chat handler 20:34:55.28 RendezvousManager: CreateSession - starting logout profile = 100:Guest 20:34:55.44 RendezvousManager: Logout complete 20:34:55.44 RendezvousManager - terminating all server calls in progress 20:34:55.44 CallManager - terminating all server calls in progress (1 in progress) 20:34:55.44 RendezvousManager: OnCredentialsEvent - starting profile login 20:34:56.61 RendezvousManager: Login complete and successfull 20:34:56.61 RendezvousManager - creating chat handler 20:34:56.62 RendezvousManager::CreateNATTraversalClient - NAT traversal available. 20:34:56.72 QuazalSelectProfileAsync - Got UserID 20:34:56.73 Transport - Largest sent is now 16 20:34:56.84 Transport - Largest received is now 43 20:34:56.84 GetUserStats requested stats for PIDs ( 2062716 ) (best:0, full:1) 20:34:56.98 SelectProfileAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=6112;PID=2062716;RVCID=87066910], private [udp:/address=[IP address redacted];port=6112;PID=2062716] 20:34:57.10 QuazalSelectProfileAsync - Got Full Stats 20:34:57.23 GetAutomatchMaps: Got [32] maps 20:34:57.25 PopulateArmyListBox - skipping race 2 20:34:57.25 PopulateArmyListBox - skipping race 0 20:34:57.25 PopulateArmyListBox - skipping race 1 20:34:57.25 PopulateArmyListBox - skipping race 3 20:34:57.25 AutoMatchForm::OnArmySelectionChanged - sending request info 20:34:57.25 GetMaxFrameTimeFromProfile: players=2 expected FPS=48.147636, bars=5, max avg=0.019, sd=0.002, 5 samples = 0.02 0.02 0.02 0.02 0.02 20:34:57.25 AutoMatchForm::OnMatchTypeSelectionChanged - sending team info 20:34:57.26 QuazalSelectProfileAsync - Got Automatch maps 20:34:58.01 Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 0.0/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0 20:35:02.29 QuazalSelectProfileAsync - GetFriends result - CacheState = 1 20:35:02.42 Profile [00000000:001f797c] selected on controller#0 20:35:02.42 Activating screen: FEMovie 20:35:02.42 Activating screen: OnlineWidget 20:35:02.42 Activating screen: FE_mm_01 20:35:02.42 Activating screen: RelicOnlineWait 20:35:02.42 GAME -- Setting campaign state to 'coh' 20:35:02.42 GAME -- Closing state 'coh' 20:35:02.44 GAME -- Setting campaign state to 'cxp2' 20:35:02.44 GAME -- Closing state 'cxp2' 20:35:02.44 GAME -- Setting campaign state to 'cxp1' 20:35:02.44 GAME -- Closing state 'cxp1' 20:35:02.44 GAME -- Setting campaign state to 'dlc1' 20:35:02.44 GAME -- Closing state 'dlc1' 20:35:02.44 GAME -- Setting campaign state to 'dlc2' 20:35:02.44 GAME -- Closing state 'dlc2' 20:35:02.45 GAME -- Setting campaign state to 'dlc3' 20:35:02.45 GAME -- Closing state 'dlc3' 20:35:06.20 Activating screen: FriendsListPopup 20:35:07.02 Requesting Relic Downloader soft throttle call [GetUserStatsAsync] has time 811 20:35:07.02 Sent message game CompanyOfHeroes softthrottle 20:35:07.02 Got dlman msg [ack game CompanyOfHeroes softthrottle] 20:35:07.94 FriendSubItemData::OnClicked - Clicked on user with ID : 889476 20:35:07.94 Activating screen: DynamicPopupMenu 20:35:08.22 Requesting Relic Downloader hard throttle call [GetUserStatsAsync] has time 2012 20:35:08.22 Sent message game CompanyOfHeroes hardthrottle 20:35:08.23 Got dlman msg [ack game CompanyOfHeroes hardthrottle] 20:35:08.64 Activating screen: FEMovie 20:35:08.64 Activating screen: OnlineWidget 20:35:08.64 UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01 20:35:08.64 Activating screen: OnlineSelectGame 20:35:08.64 Activating screen: RelicOnlineChat 20:35:08.65 Activating screen: RelicOnlineNewsScreen 20:35:08.71 Activating screen: RelicOnlineStatsScreen 20:35:08.73 Activating screen: Achievements 20:35:08.73 GAME -- Setting campaign state to 'coh' 20:35:08.73 GAME -- Closing state 'coh' 20:35:08.73 GAME -- Setting campaign state to 'cxp2' 20:35:08.73 GAME -- Closing state 'cxp2' 20:35:08.73 GAME -- Setting campaign state to 'cxp1' 20:35:08.73 GAME -- Closing state 'cxp1' 20:35:08.73 GAME -- Setting campaign state to 'dlc1' 20:35:08.73 GAME -- Closing state 'dlc1' 20:35:08.74 GAME -- Setting campaign state to 'dlc2' 20:35:08.74 GAME -- Closing state 'dlc2' 20:35:08.74 GAME -- Setting campaign state to 'dlc3' 20:35:08.74 GAME -- Closing state 'dlc3' 20:35:08.74 GAME -- Setting campaign state to 'dlc1' 20:35:08.74 GAME -- Closing state 'dlc1' 20:35:08.75 Activating screen: GameHistory 20:35:08.75 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.75 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.75 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GAME -- Error reading header for recorded game PLAYBACK:argentan_crossroads_(2).2012-12-27.23-08-25.rec 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.76 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 Activating screen: OnlineSelectGame 20:35:08.77 Activating screen: RelicOnlineTabs 20:35:08.77 Activating screen: OnlineSelectGame 20:35:08.77 Activating screen: RelicOnlineTabs 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GAME -- Error reading header for recorded game PLAYBACK:argentan_crossroads_(2).2012-12-27.23-08-25.rec 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.77 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:08.80 JoinChannelAsyncJob: Joining channel 6 20:35:08.81 ExtendInvitationAsync: Call started. 20:35:10.33 Requesting Relic Downloader soft throttle call [JoinChannelAsyncJob] has time 1534 20:35:10.33 Sent message game CompanyOfHeroes softthrottle 20:35:10.34 GetUserStats requested stats for PIDs ( 12622 52261 580457 659463 889476 1031779 1510684 1982377 2051284 2055926 2140023 2202778 2208920 2215135 2259008 2536168 3568559 3748825 3750302 4132731 4152016 4213880 4387640 4440126 4442072 4469789 4654113 4749000 4762757 4846947 4926282 5089558 5174620 5241710 ) (best:34, full:0) 20:35:10.34 Got dlman msg [ack game CompanyOfHeroes softthrottle] 20:35:10.80 Requesting Relic Downloader hard throttle call [GetTipsAsync] has time 2004 20:35:10.80 Sent message game CompanyOfHeroes hardthrottle 20:35:10.81 Got dlman msg [ack game CompanyOfHeroes hardthrottle] 20:35:12.45 QueryMatches: Got [75] maps, [186] ids, [17] advertisements, startID [1] 20:35:12.45 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 169 matches 20:35:12.46 FILESYSTEM -- filepath failure, missing alias '.sgb' 20:35:12.47 Activating screen: MessageBoxPopup 20:35:12.54 RendezvousNotifier - Received JoinRequest Event 5 (pid=889476, p1=0, p2=1, str=). 20:35:13.40 Transport - Largest sent is now 81 20:35:13.42 Transport - Largest received is now 80 20:35:13.45 Transport - Largest received is now 82 20:35:13.47 QueryMatches: Got [74] maps, [183] ids, [17] advertisements, startID [280241009] 20:35:13.47 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 149 matches 20:35:13.49 Sent message game CompanyOfHeroes disallowtraffic 20:35:13.50 Activating screen: FEMovie 20:35:13.50 Activating screen: OnlineWidget 20:35:13.50 Activating screen: OnlineSelectGame 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.50 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.51 GAME -- Error reading header for recorded game PLAYBACK:argentan_crossroads_(2).2012-12-27.23-08-25.rec 20:35:13.51 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.51 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.51 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.52 Activating screen: RelicOnlineChat 20:35:13.52 Activating screen: RelicOnlineNewsScreen 20:35:13.59 Activating screen: RelicOnlineStatsScreen 20:35:13.60 Activating screen: Achievements 20:35:13.60 GAME -- Setting campaign state to 'coh' 20:35:13.60 GAME -- Closing state 'coh' 20:35:13.60 GAME -- Setting campaign state to 'cxp2' 20:35:13.60 GAME -- Closing state 'cxp2' 20:35:13.61 GAME -- Setting campaign state to 'cxp1' 20:35:13.61 GAME -- Closing state 'cxp1' 20:35:13.61 GAME -- Setting campaign state to 'dlc1' 20:35:13.61 GAME -- Closing state 'dlc1' 20:35:13.61 GAME -- Setting campaign state to 'dlc2' 20:35:13.61 GAME -- Closing state 'dlc2' 20:35:13.61 GAME -- Setting campaign state to 'dlc3' 20:35:13.61 GAME -- Closing state 'dlc3' 20:35:13.61 GAME -- Setting campaign state to 'dlc1' 20:35:13.61 GAME -- Closing state 'dlc1' 20:35:13.62 Activating screen: GameHistory 20:35:13.62 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.62 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.62 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GAME -- Error reading header for recorded game PLAYBACK:argentan_crossroads_(2).2012-12-27.23-08-25.rec 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:35:13.63 Activating screen: OnlineSelectGame 20:35:13.63 Activating screen: RelicOnlineTabs 20:35:13.63 Activating screen: OnlineSelectGame 20:35:13.63 Activating screen: RelicOnlineTabs 20:35:13.63 Activating screen: MessageBoxPopup 20:35:13.63 ReplyToInvitationAsync: Call started. 20:35:13.63 JoinAsync: Starting AsyncJob... 20:35:13.63 Session::Reset with reason 999 and AdvertisementInternal::ResetSession() 20:35:13.63 Created Matchinfo 20:35:13.63 Got dlman msg [ack game CompanyOfHeroes disallowtraffic] 20:35:13.64 OnlineJoinAsync: initiating FetchPublicURL 20:35:13.77 Transport - Largest received is now 107 20:35:13.90 OnlineJoinAsync - RegisterLocalURLs public [udp:/address=[IP address redacted];port=6112;PID=2062716;RVCID=87066910], private [udp:/address=[IP address redacted];port=6112;PID=2062716] 20:35:15.06 GetPingAsync: Reporting Ping results for 6 players (success=6, fails=0) 20:35:18.54 QueryMatches: Got [71] maps, [182] ids, [17] advertisements, startID [280237533] 20:35:18.54 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 131 matches 20:35:18.60 JoinMatch: lstStationURLsToProbe [udp:/address=[IP address redacted];port=6112;PID=889476;RVCID=87059032] 20:35:18.60 JoinMatch: lstStationURLsToProbe [udp:/address=[IP address redacted];port=6112;PID=889476;RVCID=87059032] 20:35:18.60 JoinMatch: lstStationURLsToProbe [udp:/address=[IP address redacted];port=6112;PID=2466075;RVCID=87066054] 20:35:18.60 JoinMatch: lstStationURLsToProbe [udp:/address=[IP address redacted];port=6112;PID=2466075;RVCID=87066054] 20:35:18.60 JoinMatch: initiating ProbeStations against 2 users, 4 URLS: 20:35:19.41 Requesting Relic Downloader soft throttle call [OnlineJoinAsync] has time 802 20:35:19.41 Sent message game CompanyOfHeroes softthrottle 20:35:19.41 Got dlman msg [ack game CompanyOfHeroes softthrottle] 20:35:20.12 JoinMatch - Reporting Ping results for 2 players (good:2, bad:0, unknown: 0) 20:35:20.27 OnlineJoinAsync success 20:35:20.27 OnJoinAdvertisementSuccess - joined online match, server leave notification required 20:35:20.27 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:20.27 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:20.27 Allocated route ID=0 for PeerID 0 at WINaddr:[IP address redacted]:6112; 20:35:20.27 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:20.27 Allocated route ID=0 for PeerID 0 at WINaddr:[IP address redacted]:6112; 20:35:20.27 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:20.27 Session::Connect sid = 10B42371, hostURL = WINaddr:[IP address redacted]:6112;, local addresses = WINaddr:[IP address redacted]:6112; 20:35:20.28 Transport - Largest sent is now 601 20:35:20.33 Transport - Largest received is now 1369 20:35:20.34 Session::PollTransportEvents AddPeerMessage from peer 0 recv'd for existing pending peer 0, the local peer 20:35:20.34 Session - highest peerID now : 3 20:35:20.34 Session::PollTransportEvents AddPeerMessage from peer 0 recv'd for existing pending peer 0, the host 20:35:20.34 Session::PollTransportEvents AddPeerMessage from peer 1 recv'd for unknown peer at address list WINaddr:[IP address redacted]:6112; 20:35:20.34 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:20.34 Session::CheckAddress - initiating address check for WINaddr:[IP address redacted]:6112; 20:35:20.35 ValidateCustomData: called with 909 bytes of custom data 20:35:20.35 Client accepted Peer 3 into the match at address list = WINaddr:[IP address redacted]:6112;, routes = WINaddr:[IP address redacted]:6112; 20:35:20.35 Session::GetEvent - probing service provided NAT'd routes 20:35:20.35 Session::CheckAddress - initiating address check for WINaddr:[IP address redacted]:6112; 20:35:20.35 AdvertisementInternal::Process - EVENT_NEWPEER 20:35:20.35 ValidateCustomData: called with 535 bytes of custom data 20:35:20.35 Client accepted Peer 1 into the match at address list = WINaddr:[IP address redacted]:6112;, routes = WINaddr:[IP address redacted]:6112; 20:35:20.35 AdvertisementInternal::Process - EVENT_NEWPEER 20:35:20.35 ValidateCustomData: called with 518 bytes of custom data 20:35:20.35 Client accepted Peer 2 into the match at address list = WINaddr:[IP address redacted]:6112;, routes = 20:35:20.35 AdvertisementInternal::Process - EVENT_NEWPEER 20:35:20.35 Session::GetState - info, session's state changed to [2:STATE_CONNECTING]. 20:35:20.35 Accepted matchInfo updated 121 from host 20:35:20.37 Session::GetState - info, session's state changed to [3:STATE_CONNECTED]. 20:35:20.37 Accepted matchInfo updated 123 from host 20:35:20.37 JoinAsync - got operation info 0:SessionOperationInfo::SUCCESS 20:35:20.37 JoinAsync - completed with JoinResult = 0:JoinEvent::R_Success 20:35:20.37 Activating screen: OnlineGameSetup 20:35:20.37 Activating screen: RelicOnlineTabs 20:35:20.37 AutomatchInternal::OnJoinComplete - Completed Join with success=1 20:35:20.37 AutomatchInternal::OnJoinComplete - automatcher is no longer active - ignoring 20:35:20.37 QuickMatchInternal::OnHostComplete - Quickmatch not in host state. 20:35:20.37 JoinAsync: AsyncJob Complete... 20:35:20.38 MessageInternal::CreateChannel: Created channel 534d5347 20:35:20.38 Map 6p_kalach does not have a valid operation type 20:35:20.38 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:20.38 local PeerID 3 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:35:20.38 host PeerID 1 CONN ack= 0 ( 0ms~0) unack= 6, retry= 0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=27ms) 100.00%, pending=0, dead=0 20:35:20.38 PeerID 2 PROX ack= 0 ( 0ms~0) unack= 0, retry= 0, highwaterOOS=0 (ip=WINaddr:[IP address redacted]:6112;), pending=0, dead=0 20:35:20.38 MessageCounts: inval=0/0, seek=0/0, join=1/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/3, remove=0/0, drop=0/0, data=4/2, voice=0/0, rchk=1/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=3/3, frag=0/0, Errors=0/0 20:35:20.45 Accepted matchInfo updated 125 from host 20:35:20.46 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:20.49 Accepted matchInfo updated 127 from host 20:35:20.54 Accepted matchInfo updated 130 from host 20:35:20.58 Accepted matchInfo updated 133 from host 20:35:20.58 Transport - Largest sent is now 642 20:35:20.61 Accepted matchInfo updated 136 from host 20:35:21.00 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:21.00 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:21.11 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:21.14 Session::PeerIntroduction - Got routecheck for existing Peer 2, from address WINaddr:[IP address redacted]:6112; 20:35:21.14 Allocated route ID=0 for PeerID 2 at WINaddr:[IP address redacted]:6112; 20:35:21.14 Transport::OpenInternal request to WINaddr:[IP address redacted]:6112; 20:35:21.14 Session::CheckAddressSuccess - peer located at address WINaddr:[IP address redacted]:6112; 20:35:21.14 Peer::AcceptRoute - accepted route 7<>11 WINaddr:[IP address redacted]:6112; for peerID:2 20:35:21.14 Session::ProcessPeerIntroductionMessages sending RouteCheck response #2 to Peer 2 @ WINaddr:[IP address redacted]:6112; 20:35:21.72 Accepted matchInfo updated 137 from host 20:35:21.72 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:21.74 Accepted matchInfo updated 139 from host 20:35:21.83 Accepted matchInfo updated 142 from host 20:35:21.86 Accepted matchInfo updated 145 from host 20:35:22.00 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:22.91 Accepted matchInfo updated 146 from host 20:35:22.92 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:22.94 Accepted matchInfo updated 148 from host 20:35:22.99 Accepted matchInfo updated 151 from host 20:35:23.00 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:23.08 Accepted matchInfo updated 154 from host 20:35:23.66 QueryMatches: Got [67] maps, [177] ids, [17] advertisements, startID [280239136] 20:35:23.66 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 111 matches 20:35:24.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:24.17 Accepted matchInfo updated 155 from host 20:35:24.18 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:24.20 Accepted matchInfo updated 157 from host 20:35:24.28 Accepted matchInfo updated 160 from host 20:35:24.35 Accepted matchInfo updated 163 from host 20:35:25.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:25.67 Accepted matchInfo updated 164 from host 20:35:25.67 Accepted matchInfo updated 166 from host 20:35:25.67 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:25.77 Accepted matchInfo updated 169 from host 20:35:25.84 Accepted matchInfo updated 172 from host 20:35:26.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:27.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:28.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:28.52 QueryMatches: Got [67] maps, [172] ids, [17] advertisements, startID [280240005] 20:35:28.53 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 90 matches 20:35:29.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:30.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:31.01 Session::ProcessCheckAddressList - sending address check to WINaddr:[IP address redacted]:6112; 20:35:31.01 Session::ProcessCheckAddressList - address WINaddr:[IP address redacted]:6112; timed out 20:35:31.97 Activating screen: DynamicPopupMenu 20:35:32.53 Activating screen: RelicOnlineStatsPopup 20:35:33.01 Accepted matchInfo updated 173 from host 20:35:33.01 Accepted matchInfo updated 175 from host 20:35:33.02 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:33.10 Accepted matchInfo updated 178 from host 20:35:33.33 GetPartyStatsByID found 0 teams for user ID 2466075 20:35:33.55 Accepted matchInfo updated 181 from host 20:35:33.67 QueryMatches: Got [66] maps, [173] ids, [17] advertisements, startID [280240541] 20:35:33.68 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 76 matches 20:35:33.78 RNT_ReportPing: user 5336910:JPS1989 CANNOT connect pingMS=65535 20:35:38.03 RNT_ReportPing: user 4308446:genDjuk CANNOT connect pingMS=65535 20:35:38.51 QueryMatches: Got [67] maps, [176] ids, [17] advertisements, startID [280240729] 20:35:38.51 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 64 matches 20:35:39.41 Accepted matchInfo updated 182 from host 20:35:39.42 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:39.45 Accepted matchInfo updated 186 from host 20:35:39.45 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:39.48 Accepted matchInfo updated 188 from host 20:35:39.52 Accepted matchInfo updated 191 from host 20:35:39.55 Accepted matchInfo updated 194 from host 20:35:39.58 Accepted matchInfo updated 197 from host 20:35:40.74 Accepted matchInfo updated 198 from host 20:35:40.75 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:35:40.77 Accepted matchInfo updated 200 from host 20:35:40.83 Accepted matchInfo updated 203 from host 20:35:41.24 Accepted matchInfo updated 206 from host 20:35:42.64 RNT_ReportPing: user 5272349:PANZERSMIX CANNOT connect pingMS=65535 20:35:42.85 RNT_ReportPing: user 5254907:Ev1lLord CANNOT connect pingMS=65535 20:35:43.52 QueryMatches: Got [67] maps, [183] ids, [17] advertisements, startID [280240969] 20:35:43.52 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 72 matches 20:35:43.57 DataDeactivate - no more things to deactivate. bailing. 20:35:43.94 RNT_ReportPing: user 3311421:octonewb CANNOT connect pingMS=65535 20:35:43.94 RNT_CancelParticipation: user 1099879:chris29 left the match 20:35:48.53 QueryMatches: Got [68] maps, [186] ids, [17] advertisements, startID [280241101] 20:35:48.53 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 60 matches 20:35:52.48 RNT_ReportPing: user 2084851:staig101 CANNOT connect pingMS=65535 20:35:53.48 QueryMatches: Got [68] maps, [186] ids, [17] advertisements, startID [280241208] 20:35:53.48 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 43 matches 20:35:56.63 RNT_ReportPing: user 4119340:Smirmish CANNOT connect pingMS=65535 20:35:58.40 QueryMatches: Got [68] maps, [184] ids, [17] advertisements, startID [280241348] 20:35:58.41 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 28 matches 20:35:59.00 Transport - median kBPS [hi/cur] sent = 2.1/0.2, recvd = 3.4/0.1, #p/sec[s/r] = 6.3/3.7, max unsent 0, version err 0, merge 41 20:36:03.48 QueryMatches: Got [66] maps, [185] ids, [17] advertisements, startID [280241433] 20:36:03.48 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches 20:36:05.81 RNT_ReportPing: user 4443403:Achterbakselul1 CANNOT connect pingMS=65535 20:36:06.33 Accepted matchInfo updated 207 from host 20:36:06.34 GetMaxFrameTimeFromProfile: players=6 expected FPS=13.788262, bars=3, max avg=0.062, sd=0.010, 5 samples = 0.08 0.06 0.05 0.05 0.07 20:36:06.36 Accepted matchInfo updated 209 from host 20:36:06.44 Accepted matchInfo updated 212 from host 20:36:06.47 Accepted matchInfo updated 215 from host 20:36:08.37 QueryMatches: Got [65] maps, [187] ids, [17] advertisements, startID [280241486] 20:36:08.37 QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 2 matches 20:36:09.10 RNT_ReportPing: user 2290127:PhillipMorris CANNOT connect pingMS=65535 20:36:09.65 RNT_ReportPing: user 1422427:666greenhamster CANNOT connect pingMS=65535 20:36:12.05 Accepted matchInfo updated 216 from host 20:36:13.38 QueryMatches: Got [65] maps, [182] ids, [17] advertisements, startID [280241536] 20:36:16.88 RNT_CancelParticipation: user 1099879:chris29 left the match 20:36:17.64 RNT_ReportPing: user 3028212:Killer261 CANNOT connect pingMS=65535 20:36:21.00 local PeerID 3 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:36:21.00 host PeerID 1 CONN ack= 0 ( 65ms~0) unack= 0, retry= 1, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=37ms) 98.73%, pending=0, dead=0 20:36:21.00 PeerID 2 CONN ack= 0 (105ms~0) unack= 0, retry= 0, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=71ms) 100.00%, pending=0, dead=0 20:36:21.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=74/49, voice=0/0, rchk=13/1, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=33/32, frag=0/0, Errors=0/0 20:36:21.12 RNT_ReportPing: user 4646276:Maplord CANNOT connect pingMS=65535 20:36:24.63 RNT_ReportPing: user 2541394:thejones2009 CANNOT connect pingMS=65535 20:36:27.26 RNT_ReportPing: user 3766353:MRPSOLDIER CANNOT connect pingMS=65535 20:36:28.25 RNT_ReportPing: user 4296643:CptDruny CANNOT connect pingMS=65535 20:36:29.77 RNT_ReportPing: user 4069279:NastyLittleMan CANNOT connect pingMS=65535 20:36:38.07 RNT_ReportPing: user 2333121:Urvieh CANNOT connect pingMS=65535 20:36:38.19 RNT_ReportPing: user 5290154:wabmac CANNOT connect pingMS=65535 20:36:39.94 Accepted matchInfo updated 217 from host 20:36:40.07 SMT_StartGame_To_Everyone - received 20:36:40.07 SMT_StartGame_To_Everyone - Starting game with synclevel 0 from start message 20:36:40.07 GameSetupForm - Starting game 20:36:40.07 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.07 PopulateGameInfo - random seed:[1359401798], guid:[{73a0d3c2-9c47-4be7-84df-56b1e58c5b5e}], sync level:[0] 20:36:40.07 Accepted matchInfo updated 218 from host 20:36:40.07 AutomatchInternal::OnStartComplete - detected successful game start 20:36:40.08 Error loading [DATA:levelingCurve.lua] 20:36:40.08 Error loading [DATA:levelingCurve.lua] 20:36:40.09 Error loading [DATA:levelingCurve.lua] 20:36:40.09 Error loading [DATA:levelingCurve.lua] 20:36:40.09 Error loading [DATA:levelingCurve.lua] 20:36:40.09 Error loading [DATA:levelingCurve.lua] 20:36:40.09 MOD - Setting player (0) race to: axis 20:36:40.09 MOD - Setting player (0) race to: 3 20:36:40.09 MOD - Setting player (1) race to: axis 20:36:40.09 MOD - Setting player (1) race to: 3 20:36:40.09 MOD - Setting player (2) race to: axis_panzer_elite 20:36:40.09 MOD - Setting player (2) race to: 4 20:36:40.09 MOD - Setting player (3) race to: allies_soviets 20:36:40.09 MOD - Setting player (3) race to: 2 20:36:40.09 MOD - Setting player (4) race to: allies_soviets 20:36:40.09 MOD - Setting player (4) race to: 2 20:36:40.09 MOD - Setting player (5) race to: allies_soviets 20:36:40.09 MOD - Setting player (5) race to: 2 20:36:40.11 OnlineUpdateStateAsync: initiating state change, id = 280241009, state=2 20:36:40.12 APP -- Game Start 20:36:40.12 Sent message game CompanyOfHeroes disallowtraffic 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GAME -- Error reading header for recorded game PLAYBACK:argentan_crossroads_(2).2012-12-27.23-08-25.rec 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.12 GameInfo::ResetInfo - SyncLevel set to 0 on reset 20:36:40.13 MessageInternal::DestroyChannel: Destroyed channel 534d5347 20:36:40.15 GAME -- Ending FE 20:36:40.15 UIFrontEnd - Unloading Front End 20:36:40.17 SOUND -- Shutting down ... 20:36:40.21 SOUND -- Shutdown completed! 20:36:40.23 MessageInternal::CreateChannel: Created channel 53594e43 20:36:40.23 P2PNetworkSynchronization::RegisterPeer - info, registered remote station [1]. 20:36:40.23 P2PNetworkSynchronization::RegisterPeer - info, registered remote station [2]. 20:36:40.23 P2PNetworkSynchronization::RegisterPeer - info, registered local station [3]. 20:36:40.23 MessageInternal::CreateChannel: Created channel 474f424a 20:36:40.23 20:36:40.23 GAME -- *** Beginning mission 6p_kalach (3 Humans, 3 Computers) *** 20:36:40.23 20:36:40.23 GameObj::StartGameObj - info, network session GUID set to [{73a0d3c2-9c47-4be7-84df-56b1e58c5b5e}]. 20:36:40.41 GAME -- Recording game 20:36:40.46 Activating screen: GameLoadScreen 20:36:40.47 Got dlman msg [ack game CompanyOfHeroes disallowtraffic] 20:36:40.63 OnlineUpdateStateAsync [(4:1) PythonCoreErrorCode::Exception] 20:36:40.70 THREAD: Hyper-Threading Technology Processors are not detected. 20:36:40.77 SOUND -- Initializing ... 20:36:41.40 SOUND -- Initialization completed! 20:36:41.43 PHYSICS: detected processor(s) capable of handling 8 threads. 20:36:41.47 MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_kalach' 20:36:41.47 MOD -- Using Mod 'Eastern_Front' 20:36:41.47 Unable to load/parse precache file [DATA:scenarios\mp\6p_kalach_precache.lua] 20:36:41.56 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'. Expensive operation 20:36:41.56 Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'. Expensive operation 20:36:43.65 GameObjLoader - upgrading load_count from 0 to 1262 20:36:47.40 GameObjLoader - upgrading load_count from 1262 to 2088 20:36:47.58 PHYSICS -- Created node factory 'HVOK' 20:36:47.58 PHYSICS -- Created node factory 'DMMY' 20:36:56.03 SPDx9 -- 2701MB available Texture Memory. Unable to create 2x2 texture. 20:36:56.39 GameObjLoader - upgrading load_count from 1769 to 4779 20:37:00.01 Transport - median kBPS [hi/cur] sent = 2.1/0.4, recvd = 3.4/0.4, #p/sec[s/r] = 9.8/7.8, max unsent 0, version err 0, merge 11 20:37:00.24 GameObjLoader 0aee0608 - resetting counters 20:37:00.24 GameObjLoader 0aee0608 - LOAD_DONE 20:37:00.24 GAME - SessionSetup 20:37:00.38 CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP. 20:37:00.38 TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048 20:37:00.38 TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024 20:37:02.91 GAME - CreateGEWorld in 2665 ms 20:37:02.92 TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed. 20:37:03.15 GAME - SessionSetup finished in 2911 ms 20:37:03.16 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:03.16 GAME - WaterReflectionManagerSetup 20:37:03.16 GAME - WaterReflectionManagerSetup finished in 0 ms 20:37:04.27 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:04.27 MessageInternal::CreateChannel: Created channel 474d4343 [Cope FactionFix] - Trying to hook function [Cope FactionFix] - Got LuaState @0x07148408 [Cope FactionFix] - patched @0x64ae5491 [Cope FactionFix] - hooked @0x64ae5492 [Cope FactionFix] - patched @0x64ae4a07 [Cope FactionFix] - hooked @0x64ae4a07 [Cope FactionFix] - Hook installed 20:37:04.43 ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded. 20:37:05.01 MOD -- Player (unused player) (frame 0) (KillPlayer) 20:37:05.01 MOD -- Player (unused player) (frame 0) (KillPlayer) 20:37:05.69 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:05.70 MessageInternal::CreateChannel: Created channel 4d4f444d 20:37:06.34 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 20:37:06.35 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 20:37:06.35 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 20:37:06.35 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 20:37:06.35 BindingsSystem -- Cannot create binding. Unknown type 'repair_radius_circle' 20:37:06.86 SPEECHMANAGER -- Loaded in 0.474137 seconds 20:37:07.46 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:07.77 GameObjLoader - upgrading load_count from 0 to 319 20:37:08.71 GameObjLoader 0aee0768 - resetting counters 20:37:08.71 GameObjLoader 0aee0768 - LOAD_DONE 20:37:09.61 PreloadResources took 834ms. 20:37:09.61 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:09.84 GAME -- Loading completed (29 seconds) 20:37:09.84 SIM -- Setting SyncErrorChecking level to Low 20:37:09.89 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:11.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:11.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:13.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:13.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:15.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:15.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:17.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:17.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:19.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:19.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:21.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:21.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:22.01 local PeerID 3 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:37:22.01 host PeerID 1 CONN ack= 0 ( 76ms~0) unack= 0, retry= 2, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=35ms) 98.73%, pending=0, dead=0 20:37:22.01 PeerID 2 CONN ack= 0 (176ms~0) unack= 0, retry= 4, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=73ms) 95.06%, pending=0, dead=0 20:37:22.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=151/181, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=33/33, frag=0/0, Errors=0/0 20:37:23.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:23.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:25.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:25.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:27.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:27.17 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:29.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:29.17 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:31.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:31.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:33.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:33.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:35.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:35.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:37.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:37.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:39.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:39.19 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:41.00 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000003, pid 0:2062716, mSnowflake]" finished loading with checksum [3014973681]. 20:37:41.20 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:889476, Slin1988]" finished loading with checksum [3014973681]. 20:37:42.97 LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2466075, Prawda]" finished loading with checksum [3014973681]. 20:37:42.98 Activating screen: GameScreen 20:37:42.98 Activating screen: Decorators_widescreen 20:37:42.98 Activating screen: Taskbar_widescreen 20:37:42.98 Activating screen: SubtitleScreen 20:37:42.98 Activating screen: TextOverlayScreen 20:37:43.11 PerformanceRecorder::StartRecording for game size 6 20:37:43.11 GAME -- Starting mission... 20:37:44.44 We do not have a sync command for peer=1, currentframe=0 20:37:44.44 We do not have a sync command for peer=2, currentframe=0 20:37:44.75 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 2) (CmdAI) 20:37:45.72 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 10) (CmdAI) 20:37:45.72 MOD -- Player CPU - Expert set to AI Type: AI Player (frame 10) (CmdAI) 20:37:47.70 Warning: binding repeat_2(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12] 20:37:47.72 Warning: binding repeat_5(Ability: abilities\smg_mp40) -- ui index '0' out of bounds; range is [1, 12] 20:37:48.95 Warning: non auto-match upgrade not found in AE, tuning 20:37:59.97 Activating screen: Command_Tree 20:38:01.00 Transport - median kBPS [hi/cur] sent = 2.1/0.9, recvd = 3.4/0.8, #p/sec[s/r] = 8.9/8.7, max unsent 0, version err 0, merge 5 20:38:23.00 local PeerID 3 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:38:23.00 host PeerID 1 CONN ack= 0 (175ms~0) unack= 1, retry= 2, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=39ms) 99.59%, pending=0, dead=0 20:38:23.00 PeerID 2 CONN ack= 0 (175ms~0) unack= 1, retry= 32, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=64ms) 92.66%, pending=0, dead=0 20:38:23.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=654/684, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=33/33, frag=0/0, Errors=0/0 20:38:41.22 Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background' 20:39:02.00 Transport - median kBPS [hi/cur] sent = 2.1/1.2, recvd = 3.4/0.9, #p/sec[s/r] = 17.8/17.0, max unsent 0, version err 0, merge 0 20:39:19.64 GAME -- SimulationController::Pause 0 20:39:24.01 local PeerID 3 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:39:24.01 host PeerID 1 CONN ack= 0 (154ms~0) unack= 0, retry= 2, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=32ms) 99.79%, pending=0, dead=0 20:39:24.01 PeerID 2 CONN ack= 0 (154ms~0) unack= 0, retry= 83, highwaterOOS=0 @WINaddr:[IP address redacted]:6112; (ping=69ms) 91.17%, pending=0, dead=0 20:39: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=906/941, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=33/33, frag=0/0, Errors=0/0 20:39:30.28 Activating screen: pause_menu 20:39:31.22 Activating screen: prompt_generic 20:39:31.82 P2PNetworkSynchronization::Stop - info, stop called. m_peers will be cleared. 20:39:31.82 MOD -- Player mSnowflake (network abort) (frame 768) (KillPlayer) 20:39:31.82 GameApp::ReportStats 20:39:31.82 ReportMatchResults - onlyReportOnWin = 0 20:39:31.82 ReportMatchResults - SimID:1000, raceID:1, teamID:0, uid:0:2062716, result:1:PS_ABORTED 20:39:31.82 ReportMatchResults - SimID:1001, raceID:1, teamID:0, uid:0:889476, still playing, ignoring 20:39:31.82 ReportMatchResults - SimID:1002, raceID:3, teamID:0, uid:0:2466075, still playing, ignoring 20:39:31.82 ReportMatchResults - SimID:1003, raceID:5, teamID:1, uid:0:0, AI player, ignoring 20:39:31.82 ReportMatchResults - SimID:1004, raceID:5, teamID:1, uid:0:0, AI player, ignoring 20:39:31.82 ReportMatchResults - SimID:1005, raceID:5, teamID:1, uid:0:0, AI player, ignoring 20:39:31.82 ReportMatchResults - reporting normal game results for match 0:280241009 20:39:31.82 ReportSimStats - storing simulation results for match 0:280241009 20:39:31.82 ReportStats - ReportSimStats failed 20:39:31.82 GameApp::Quit( exitValue = 0 ) 20:39:31.84 Activating screen: AppRestartingForm 20:39:31.85 SetupProductLoadingArt - choosing bgArt = 4 (gold=1) 20:39:31.85 Widget attempting to remove a non-existance listener. Widget:bkg_COH 20:39:31.85 Widget attempting to remove a non-existance listener. Widget:bkg_CXP1 20:39:31.85 Widget attempting to remove a non-existance listener. Widget:bkg_CXP2 20:39:31.85 Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1 20:39:31.85 Widget attempting to remove a non-existance listener. Widget:bkg_ToV 20:39:31.85 OnlineReportResults - player=2062716, gid = 280241009, time=96, result=1, team=0, race=1, simPlayerID=1000 20:39:31.85 OnlineReportResults: Call started. 20:39:31.85 Session is marking PeerID 1 for death 20:39:31.85 Session is marking PeerID 2 for death 20:39:31.85 Session is marking the local peer PeerID 3 for death 20:39:31.85 OnlineLeaveAdvertisementAsync: Match id = 280241009 started, nothing to do 20:39:31.85 Session::GetEvent DestroyPeer success Peer 1 removed 20:39:31.85 Session::DestroyPeer - deallocating peer 1 20:39:31.85 Session - Requested Disconnect for connectionID 3 20:39:31.85 Deallocated route ID=5 for PeerID 1 at WINaddr:[IP address redacted]:6112; 20:39:31.85 Session - Requested Disconnect for connectionID 5 20:39:31.85 Closed route 5<>9 for PeerID 1 at WINaddr:[IP address redacted]:6112; 20:39:31.85 AdvertisementInternal::Process - EVENT_DESTROYPEER 20:39:31.85 P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [1]. 20:39:31.85 OnDestroyedPeerEvent - calling RemovePeerFromSync. 20:39:31.85 GameObjController - OnMatchEvent: event type 1 20:39:31.85 GameObj::OnRemoteStationDisconnected - info, remote station [1] was disconnected reason 1 20:39:31.85 GameObj::OnRemoteStationDisconnected - info, remote station [1] is disconnected 20:39:31.85 onplayerdrops 1001 0 20:39:31.85 ArbitrationMessageNeeded OnPlayerDrops adding player 1001 [889476] as victorious ally 20:39:31.85 Session::GetEvent DestroyPeer success Peer 2 removed 20:39:31.85 Session::DestroyPeer - deallocating peer 2 20:39:31.85 Session - Requested Disconnect for connectionID 6 20:39:31.85 Deallocated route ID=7 for PeerID 2 at WINaddr:[IP address redacted]:6112; 20:39:31.85 Session - Requested Disconnect for connectionID 7 20:39:31.85 Closed route 7<>11 for PeerID 2 at WINaddr:[IP address redacted]:6112; 20:39:31.85 AdvertisementInternal::Process - EVENT_DESTROYPEER 20:39:31.85 P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [2]. 20:39:31.85 OnDestroyedPeerEvent - calling RemovePeerFromSync. 20:39:31.85 GameObjController - OnMatchEvent: event type 1 20:39:31.85 GameObj::OnRemoteStationDisconnected - info, remote station [2] was disconnected reason 1 20:39:31.85 GameObj::OnRemoteStationDisconnected - info, remote station [2] is disconnected 20:39:31.85 onplayerdrops 1002 0 20:39:31.85 ArbitrationMessageNeeded OnPlayerDrops adding player 1002 [2466075] as victorious ally 20:39:31.85 Session::GetEvent DestroyPeer success Peer 3 removed 20:39:31.85 Session::DestroyPeer - deallocating peer 3 20:39:31.85 Session - Requested Disconnect for connectionID 2 20:39:31.85 Deallocated route ID=4 for PeerID 3 at WINaddr:[IP address redacted]:6112; 20:39:31.85 Session - Requested Disconnect for connectionID 4 20:39:31.85 Closed route 4<>4 for PeerID 3 at WINaddr:[IP address redacted]:6112; 20:39:31.85 AdvertisementInternal::Process - EVENT_DESTROYPEER 20:39:31.85 Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED]. 20:39:31.85 P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [3]. 20:39:31.85 OnDestroyedPeerEvent - calling RemovePeerFromSync. 20:39:31.85 GameObjController - OnMatchEvent: event type 1 20:39:31.85 GAME -- Frame 768 - SimKillPlayer - Destroying SimPlayer 1000, reason 0 20:39:31.85 GameApp::ReportStats 20:39:31.85 ReportStats - already reported for match 0:280241009 20:39:31.85 LeaveMatchInternal - no action taken, needLeave=0, service=0 20:39:31.85 Destroyed Matchinfo 20:39:31.85 GameApp::ShutFrontEnd 20:39:31.85 GameObj::ShutdownGameObj 20:39:31.85 GAME -- Ending mission (Connection Lost) 20:39:31.89 GameObjLoader 0aee0768 - Unload 20:39:31.89 GameObjLoader 0aee0768 - resetting counters 20:39:31.89 Session - Expected ConnectionID 5 doesn't exist, will close remote. 20:39:31.89 Session - Expected ConnectionID 5 doesn't exist, will close remote. 20:39:31.92 MessageInternal::DestroyChannel: Destroyed channel 4d4f444d 20:39:31.92 MOD -- Game Over at frame 768 20:39:31.93 Session - Expected ConnectionID 7 doesn't exist, will close remote. 20:39:31.93 Session - Expected ConnectionID 7 doesn't exist, will close remote. 20:39:32.16 GameObjLoader 0aee0608 - Unload 20:39:32.16 GameObjLoader 0aee0608 - resetting counters 20:39:32.34 SOUND -- Shutting down ... 20:39:32.39 SOUND -- Shutdown completed! 20:39:32.40 MessageInternal::DestroyChannel: Destroyed channel 474d4343 20:39:32.40 MessageInternal::DestroyChannel: Destroyed channel 53594e43 20:39:32.40 ~FEAppListener - Flushing queued online notifications 20:39:33.09 DLLDriverLinker -- 2 DLL drivers found. 20:39:33.09 ActionDatabase : [2572] global actions, [27] dummy actions. 20:39:33.10 NetworkManager::Destroy - destroying network manager 20:39:33.10 RendezvousManager::Shutdown - deleting instance 20:39:33.10 RendezvousManager - terminating all server calls in progress 20:39:33.10 CallManager - terminating all server calls in progress (2 in progress) 20:39:33.10 RendezvousManager - destroying chat handler 20:39:33.10 RendezvousManager::DestroyNATTraversalClient - NAT traversal no longer available. 20:39:33.10 ~RendezvousManager - disconnecting from server 20:39:33.23 MessageInternal::DestroyChannel: Destroyed channel 51434b4d 20:39:33.23 MessageInternal::DestroyChannel: Destroyed channel 50525459 20:39:33.23 PartyInternal: Fade to black 20:39:33.23 AutomatchInternal: Fade to black 20:39:33.23 PeerRemoveAll - flushing local session peer data 20:39:33.23 Session::Reset with reason 999 and MatchInternal::PeerRemoveAll 20:39:33.23 MessageInternal::DestroyChannel: Destroyed channel 47535450 20:39:33.23 Session - Requested Disconnect for connectionID 1 20:39:33.23 SessionManager::UnregisterSession - Unregistering session 0839f750 20:39:33.23 SessionManager::~SessionManager - uninitializing 20:39:33.24 Net::ThreadFunction - Returning from the network thread function... 20:39:33.24 Transport::~Transport - shutting down transport 20:39:33.24 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:39:33.24 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:39:33.24 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:39:33.24 Transport::~Connection - closing WINaddr:[IP address redacted]:6112;,WINaddr:[IP address redacted]:6112; 20:39:33.25 AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job OnlineReportResults is still running 20:39:33.25 AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job DisconnectAsync is still running 20:39:33.25 AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job OnlineLeaveAdvertisementAsync is still running 20:39:33.25 Wrote 870 bytes to network datastore login_cache.bin 20:39:33.25 Wrote 137946 bytes to network datastore global_cache.bin 20:39:33.27 Wrote 219322 bytes to network datastore ping_cache.bin 20:39:33.27 Datastore -- uninitialize complete 20:39:33.27 QuazalInitializer - static uninit of Quazal library 20:39:33.27 QuazalInitializer - unhooking memory 0 currently allocated, 110331 allocations, total of 25294688 bytes