-- Log file for all dbTracef messages --

17:56:26.42   NetworkManager::Create - creating network manager
17:56:26.42   NetFileAccessor::Open - error, unable to open or create file.
17:56:26.42   Skipping empty datastore login_cache.bin
17:56:26.42   NetFileAccessor::Open - error, unable to open or create file.
17:56:26.42   Skipping empty datastore global_cache.bin
17:56:26.42   NetFileAccessor::Open - error, unable to open or create file.
17:56:26.42   Skipping empty datastore ping_cache.bin
17:56:26.42   QuazalInitializer - static initializing Quazal library
17:56:27.01   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
17:56:27.01   WinTransport - CreateSocket exclusive broadcast socket was available.
17:56:27.01   WinTransport - CreateSocket listening for broadcasts on default port
17:56:27.02   WinTransport - Host Name: Reflection, aliases: , type=AF_INET, len=4
17:56:27.02   WinTransport - Host IP Address #0: 7.19.158.52
17:56:27.02   WinTransport - Host IP Address #1: 192.168.1.10
17:56:27.02   WinTransport - WARNING, MultipleNetwork adapters may cause problems
17:56:27.02   WinTransport - Interface #0: ip:192.168.1.10, broadcast:192.168.1.10, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
17:56:27.02   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
17:56:27.02   WinTransport - Interface #2: ip:7.19.158.52, broadcast:7.19.158.52, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
17:56:27.02   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:56:27.02   WinTransport - Quazal address string = udp:/address=7.19.158.52;port=6112
17:56:27.02   SessionManager - Peer Header Size = 16 bytes
17:56:27.02   SessionManager - Game Data overhead = 7 bytes
17:56:27.02   SessionManager - Proxy overhead = 7 bytes
17:56:27.02   Net::ThreadFunction - Entering network thread function...
17:56:27.02   MessageInternal::CreateChannel: Created channel 47535450
17:56:27.02   Session::Initialize - info, initializing session object, using threads.
17:56:27.02   SessionManager::RegisterSession - Registering new session 04361698
17:56:27.02   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:56:27.02   AutomatchInternal: Instantiating
17:56:27.02   PartyInternal: Instantiating
17:56:27.02   MessageInternal::CreateChannel: Created channel 50525459
17:56:27.02   MessageInternal::CreateChannel: Created channel 51434b4d
17:56:27.03   RelicOnlineAccountCreate::PopulateCDKeyFields - no offline account found.
17:56:27.03   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:56:27.03   MessageInternal::CreateChannel: Created channel 534d5347
17:56:27.03   MessageInternal::CreateChannel: Created channel 474d4343
17:56:27.03   MessageInternal::CreateChannel: Created channel 474f424a
17:56:27.03   MessageInternal::CreateChannel: Created channel 4d4f444d
17:56:27.03   MessageInternal::CreateChannel: Created channel 53594e43
17:56:27.03   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:56:27.03   MessageInternal::DestroyChannel: Destroyed channel 474d4343
17:56:27.03   MessageInternal::DestroyChannel: Destroyed channel 474f424a
17:56:27.03   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
17:56:27.03   MessageInternal::DestroyChannel: Destroyed channel 53594e43
17:56:27.03   GAME -- Available memory: 8188MB Physical RAM, 10112MB Pagefile, 4095 Virtual Address Space
17:56:27.04   Transport - Largest received is now 19
17:56:27.04   Transport::OpenInternal request to WINaddr:7.19.158.52:6112;
17:56:27.20   Transport::OpenInternal request to WINaddr:7.55.76.243:6112;
17:56:27.20   Transport - Largest sent is now 30
17:56:27.23   Transport::OpenInternal request to WINaddr:7.53.0.239:6112;
17:56:27.29   Transport - Largest received is now 838
17:56:29.26   Transport::OpenInternal request to WINaddr:7.94.25.46:6112;
17:56:32.14   DLLDriverLinker -- Adding driver 'spDx10.dll'.
17:56:32.21   DLLDriverLinker -- Adding driver 'spDx9.dll'.
17:56:32.21   DLLDriverLinker -- 2 DLL drivers found.
17:56:32.29   SPDx10 -- Adapter [AMD Radeon HD 7700 Series]: 1003MB dedicated video memory, 0MB dedicated system memory and 3092MB shared system memory.
17:56:33.02   DLLDriverLinker -- 2 DLL drivers found.
17:56:33.45   SPOOGE - Driver[DirectX10 Rendering Device] version[4,36]
17:56:33.45   GAME -- Resolution set to 1600x1200 (fullscreen).
17:56:33.49   SPDx10 -- Adapter Description = AMD Radeon HD 7700 Series
17:56:33.49   SPDx10 -- Driver Vendor = 0x00001002  Device = 0x0000683d  SubSys = 0xe215174b  Rev = 0x00000000
17:56:33.49   SPDx10 -- Driver Version  Product = 0x0008  Version = 0x0011  SubVersion = 0x00  Build = 13.33
17:56:33.49   SPDx10 -- Driver LUID = 0x00000000-0x0000a274
17:56:33.49   SPDx10 -- 1003MB dedicated video memory, 0MB dedicated system memory and 3092MB shared system memory available.
17:56:33.51   ShaderDatabase: using shader profile [ps40]
17:56:34.62   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
17:56:35.44   GameObjLoader 090570b8 - resetting counters
17:56:35.44   GameObjLoader 090570b8 - Created loader
17:56:35.44   GameObjLoader 09056e38 - resetting counters
17:56:35.44   GameObjLoader 09056e38 - Created loader
17:56:35.69   GAME -- Beginning FE
17:56:35.69   Sent message game CompanyOfHeroes started 10888 602 allowtraffic
17:56:35.69   RemoteDLManager - Connection Restored.
17:56:35.69   UIFrontEnd - Loading Front End
17:56:35.69   THREAD: Hyper-Threading Technology Processors are not detected.
17:56:35.92   SOUND -- Initializing ...
17:56:36.10   INNIMapDCA Key not found: sp_speechducker::time
17:56:37.40   SOUND -- Initialization completed!
17:56:37.40   UIFrontEnd - Initializing Forms
17:56:41.29   CampaignFilter::BindFilterSpecificWidgets()
17:56:41.95   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 1024.
17:56:42.25   Activating screen: MovieScreen
17:56:42.25   Got dlman msg [dlmanager version 1.0 peertraffic 0 uploadlimit 2147483647 seedratio 3]
17:56:46.59   Activating screen: AppLoadingForm
17:56:46.59   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:56:49.48   GAME -- (worldmanager) Cannot find original scenario "4p_vyazma_challenge" referenced from data:scenarios\mp\challenge_vyazma.scenref
17:56:51.23   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
17:56:51.23   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
17:56:51.23   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
17:56:51.25   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
17:56:51.26   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
17:56:51.26   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
17:56:51.91   GAME -- Using player profile REFLECTION
17:56:52.73   Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
17:56:53.11   Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
17:56:56.29   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
17:56:56.29   RendezvousManager: CreateSession - starting profile=Guest login
17:56:56.44   GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure.
17:56:56.44   RendezvousManager - unable to connect to the server as guest
17:56:56.44   OnConnect: connect failure
17:56:56.44   RendezvousManager::Shutdown - deleting instance
17:56:56.44   RendezvousManager - terminating all server calls in progress
17:56:56.44   CallManager - terminating all server calls in progress (1 in progress)
17:56:56.44   RendezvousManager - destroying chat handler
17:56:56.44   ~RendezvousManager - m_state = 7, no need for server disconnect
17:56:56.46   OnConnect: this wasnt a reconnect, no need for autologin
17:56:56.47   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:56:56.47   CRC & Version Info : 0000025a:703d9745:ec3c9e0d eastern_front:602:factionfix.dll 1
17:56:56.47   Activating screen: FEMovie
17:56:56.47   Activating screen: OnlineWidget
17:56:56.47   Activating screen: RelicOnlineLogin
17:56:56.54   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
17:56:56.54   You have a valid version of the game.
17:56:56.54   AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now
17:56:56.54   installed_products = ( DLC1 DLC2 DLC3 )
17:56:56.54   OnLogin: no previous login, auto selecting profile not required
17:56:56.54   Activating screen: MessageBoxPopup
17:56:56.54   Activating screen: MessageBoxPopup
17:56:56.54   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
17:56:58.57   Beginning media verification...
17:56:58.57   Validator type : validators\udro\udro.dll
17:56:58.57   Activating screen: MessageBoxPopup
17:56:59.28   Activating screen: FE_mm_01
17:56:59.31   GAME -- Setting campaign state to 'dlc1'
17:56:59.31   GAME -- Closing state 'dlc1'
17:56:59.31   GAME -- Setting campaign state to 'dlc2'
17:56:59.31   GAME -- Closing state 'dlc2'
17:56:59.32   GAME -- Setting campaign state to 'dlc3'
17:56:59.32   GAME -- Closing state 'dlc3'
17:57:01.89   Activating screen: MessageBoxPopup
17:57:01.89   Created Matchinfo
17:57:01.89   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
17:57:01.89   starting LAN hosting
17:57:01.93   OnJoinAdvertisementSuccess - joined online match, server leave notification required
17:57:01.93   starting local hosting
17:57:01.93   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:57:01.93   Allocated route ID=0 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:57:01.93   Transport::OpenInternal request to WINaddr:7.19.158.52:6112;
17:57:01.93   Session::Host sid = DEF08F684E7E32EB, hostURL = , local addresses = WINaddr:7.19.158.52:6112;
17:57:01.93   ValidateCustomData: called with 205 bytes of custom data
17:57:01.93   Host accepted Peer 1 into the match at address list=WINaddr:7.19.158.52:6112;, routes=WINaddr:7.19.158.52:6112;
17:57:01.93   AdvertisementInternal::Process - EVENT_NEWPEER
17:57:01.93   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
17:57:01.95   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
17:57:01.95   hosting - Session is connected
17:57:01.95   Net::Session::SetVisible - session is set to VISIBLE.
17:57:01.97   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:7.19.158.52:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:57:01.97   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=1/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
17:57:01.97   hosting completed successfully
17:57:01.97   HostAsync - completed with HostResult = 0
17:57:01.97   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
17:57:01.97   Activating screen: OnlineGameSetup
17:57:01.97   MessageInternal::CreateChannel: Created channel 534d5347
17:57:01.97   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:01.97   MatchInternal::SetMatchType - new type 14 - updating server
17:57:02.08   SetVisible called while !IsConnected
17:57:02.08   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:02.09   Activating screen: RelicOnlineChat
17:57:02.09   Activating screen: RelicOnlineNewsScreen
17:57:02.09   Activating screen: RelicOnlineStatsScreen
17:57:02.09   Activating screen: Achievements
17:57:02.09   GAME -- Setting campaign state to 'dlc1'
17:57:02.09   GAME -- Closing state 'dlc1'
17:57:02.10   GAME -- Setting campaign state to 'dlc1'
17:57:02.10   GAME -- Closing state 'dlc1'
17:57:02.10   GAME -- Setting campaign state to 'dlc2'
17:57:02.10   GAME -- Closing state 'dlc2'
17:57:02.10   Transport - Largest received is now 999
17:57:02.11   GAME -- Setting campaign state to 'dlc3'
17:57:02.11   GAME -- Closing state 'dlc3'
17:57:02.11   GAME -- Setting campaign state to 'dlc1'
17:57:02.11   GAME -- Closing state 'dlc1'
17:57:02.12   Activating screen: GameHistory
17:57:02.12   GAME -- Setting campaign state to 'dlc1'
17:57:02.12   GAME -- Closing state 'dlc1'
17:57:02.12   GAME -- Setting campaign state to 'dlc2'
17:57:02.12   GAME -- Closing state 'dlc2'
17:57:02.12   Transport - Largest received is now 1071
17:57:02.13   GAME -- Setting campaign state to 'dlc3'
17:57:02.13   GAME -- Closing state 'dlc3'
17:57:02.13   Activating screen: OnlineGameSetup
17:57:02.13   Activating screen: RelicOnlineTabs
17:57:02.13   Activating screen: ScreenTipPopup
17:57:02.13   AutomatchInternal::OnHostComplete - Completed Host with success=1
17:57:02.13   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
17:57:02.13   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
17:57:02.14   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:02.14   Net::Session::SetVisible - session is set to VISIBLE.
17:57:02.14   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:02.23   Activating screen: ScreenTipPopup
17:57:02.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:02.33   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:02.36   GAME -- Setting campaign state to 'dlc1'
17:57:02.36   GAME -- Closing state 'dlc1'
17:57:02.37   GAME -- Setting campaign state to 'dlc2'
17:57:02.37   GAME -- Closing state 'dlc2'
17:57:02.37   GAME -- Setting campaign state to 'dlc3'
17:57:02.37   GAME -- Closing state 'dlc3'
17:57:03.87   Activating screen: RaceSelectionPopup
17:57:05.33   Activating screen: RaceSelectionPopup
17:57:07.02   Activating screen: RaceSelectionPopup
17:57:19.45   Transport - Largest received is now 1077
17:57:19.47   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:19.47   Net::Session::SetVisible - session is set to VISIBLE.
17:57:19.48   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:19.51   TGAIO -- TGA file 'Data:scenarios\mp\contryside_map_base.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
17:57:19.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:19.59   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:23.97   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:23.97   Net::Session::SetVisible - session is set to VISIBLE.
17:57:23.98   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:23.99   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:24.05   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:26.91   Transport - Largest received is now 1083
17:57:26.93   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:26.93   Net::Session::SetVisible - session is set to VISIBLE.
17:57:26.93   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:27.00   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:27.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:28.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 3.2/1.4, #p/sec[s/r] = 0.7/1.1, max unsent 0, version err 0, merge 2
17:57:33.46   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:33.46   Net::Session::SetVisible - session is set to VISIBLE.
17:57:33.46   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:33.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:33.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:34.94   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:34.94   Net::Session::SetVisible - session is set to VISIBLE.
17:57:34.94   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:35.01   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:35.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:36.25   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:36.25   Net::Session::SetVisible - session is set to VISIBLE.
17:57:36.25   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:36.26   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:36.31   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:41.25   Activating screen: DynamicPopupMenu
17:57:41.72   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:41.72   MatchInternal::SetMatchType - new type 0 - updating server
17:57:41.72   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:41.72   Net::Session::SetVisible - session is set to VISIBLE.
17:57:41.72   Net::Session::SetVisible - session is set to VISIBLE.
17:57:41.73   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:41.73   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:41.77   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:41.77   Net::Session::SetVisible - session is set to VISIBLE.
17:57:41.77   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:41.80   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:41.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:42.27   Activating screen: DynamicPopupMenu
17:57:42.82   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:42.82   Net::Session::SetVisible - session is set to VISIBLE.
17:57:42.82   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:42.83   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:42.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:43.67   Activating screen: DynamicPopupMenu
17:57:44.08   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:44.08   Net::Session::SetVisible - session is set to VISIBLE.
17:57:44.08   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:44.09   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:44.14   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:44.57   Activating screen: DynamicPopupMenu
17:57:44.65   OnlinePlayerListEntry::OnEnterSlot - Slot 5 selected...
17:57:44.67   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:44.67   Net::Session::SetVisible - session is set to VISIBLE.
17:57:44.68   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:44.72   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:44.77   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:46.91   Activating screen: DynamicPopupMenu
17:57:47.84   GameSetupForm - UpdateMatchType: Setting match type to 0: BASIC_MATCH
17:57:47.84   Net::Session::SetVisible - session is set to VISIBLE.
17:57:47.84   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:47.86   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:47.92   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:48.45   Activating screen: DynamicPopupMenu
17:57:49.92   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:49.92   MatchInternal::SetMatchType - new type 14 - updating server
17:57:49.92   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:49.92   Net::Session::SetVisible - session is set to VISIBLE.
17:57:49.92   Net::Session::SetVisible - session is set to VISIBLE.
17:57:49.92   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:49.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:49.97   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:49.97   Net::Session::SetVisible - session is set to VISIBLE.
17:57:49.98   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:50.00   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:50.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:50.42   Activating screen: RaceSelectionPopup
17:57:51.32   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:51.32   Net::Session::SetVisible - session is set to VISIBLE.
17:57:51.33   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:51.34   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:51.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:51.93   Activating screen: RaceSelectionPopup
17:57:52.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:53.62   Activating screen: RaceSelectionPopup
17:57:54.20   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:57:54.20   Net::Session::SetVisible - session is set to VISIBLE.
17:57:54.21   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:57:54.22   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:54.28   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:57:56.69   Activating screen: RaceSelectionPopup
17:58:00.94   Transport::OpenInternal request to WINaddr:7.37.7.123:6112;
17:58:02.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:7.19.158.52:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:58:02.01   MessageCounts: inval=0/0, seek=0/19, join=0/0, integ=0/0, seek_reply=57/10, 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=43/30, frag=0/0, Errors=0/0
17:58:22.63   Activating screen: GameHistory
17:58:22.63   Activating screen: RelicOnlineTabs
17:58:22.63   Activating screen: ScreenTipPopup
17:58:25.39   Activating screen: OnlineGameSetup
17:58:25.39   Activating screen: RelicOnlineTabs
17:58:25.39   Activating screen: ScreenTipPopup
17:58:29.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 3.2/0.6, #p/sec[s/r] = 0.8/2.2, max unsent 0, version err 0, merge 1
17:58:50.84   Activating screen: MessageBoxPopup
17:58:50.84   Session is marking the local peer PeerID 1 for death
17:58:50.84   Session::GetEvent DestroyPeer success Peer 1 removed
17:58:50.84   Session::DestroyPeer - deallocating peer 1
17:58:50.84   Session - Requested Disconnect for connectionID 2
17:58:50.84   Deallocated route ID=3 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:58:50.84   Session - Requested Disconnect for connectionID 3
17:58:50.84   Closed route 3<>3 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:58:50.84   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:58:50.84   Destroyed Matchinfo
17:58:50.88   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:58:50.88   PeerRemoveAll - flushing local session peer data
17:58:50.88   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:58:50.90   Activating screen: FEMovie
17:58:50.90   Activating screen: OnlineWidget
17:58:50.90   Activating screen: FE_mm_01
17:58:50.92   GAME -- Setting campaign state to 'dlc1'
17:58:50.92   GAME -- Closing state 'dlc1'
17:58:50.92   GAME -- Setting campaign state to 'dlc2'
17:58:50.92   GAME -- Closing state 'dlc2'
17:58:50.93   GAME -- Setting campaign state to 'dlc3'
17:58:50.93   GAME -- Closing state 'dlc3'
17:58:50.93   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:58:50.93   GAME -- Setting campaign state to 'dlc1'
17:58:50.93   GAME -- Closing state 'dlc1'
17:58:50.94   GAME -- Setting campaign state to 'dlc2'
17:58:50.94   GAME -- Closing state 'dlc2'
17:58:50.94   GAME -- Setting campaign state to 'dlc3'
17:58:50.94   GAME -- Closing state 'dlc3'
17:58:52.35   Activating screen: MessageBoxPopup
17:58:52.35   Created Matchinfo
17:58:52.35   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
17:58:52.35   starting LAN hosting
17:58:52.39   OnJoinAdvertisementSuccess - joined online match, server leave notification required
17:58:52.39   starting local hosting
17:58:52.39   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:58:52.39   Allocated route ID=0 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:58:52.39   Transport::OpenInternal request to WINaddr:7.19.158.52:6112;
17:58:52.39   Session::Host sid = DE7BC889847DE0C8, hostURL = , local addresses = WINaddr:7.19.158.52:6112;
17:58:52.39   ValidateCustomData: called with 205 bytes of custom data
17:58:52.39   Host accepted Peer 1 into the match at address list=WINaddr:7.19.158.52:6112;, routes=WINaddr:7.19.158.52:6112;
17:58:52.39   AdvertisementInternal::Process - EVENT_NEWPEER
17:58:52.39   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
17:58:52.41   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
17:58:52.41   hosting - Session is connected
17:58:52.41   Net::Session::SetVisible - session is set to VISIBLE.
17:58:52.42   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:7.19.158.52:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:58:52.42   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=1/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
17:58:52.42   hosting completed successfully
17:58:52.42   HostAsync - completed with HostResult = 0
17:58:52.42   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
17:58:52.42   Activating screen: OnlineGameSetup
17:58:52.42   MessageInternal::CreateChannel: Created channel 534d5347
17:58:52.42   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:58:52.42   MatchInternal::SetMatchType - new type 14 - updating server
17:58:52.52   SetVisible called while !IsConnected
17:58:52.52   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:58:52.54   Activating screen: RelicOnlineChat
17:58:52.54   Activating screen: RelicOnlineNewsScreen
17:58:52.54   Activating screen: RelicOnlineStatsScreen
17:58:52.54   Activating screen: Achievements
17:58:52.54   GAME -- Setting campaign state to 'dlc1'
17:58:52.54   GAME -- Closing state 'dlc1'
17:58:52.54   GAME -- Setting campaign state to 'dlc2'
17:58:52.54   GAME -- Closing state 'dlc2'
17:58:52.55   GAME -- Setting campaign state to 'dlc3'
17:58:52.55   GAME -- Closing state 'dlc3'
17:58:52.55   GAME -- Setting campaign state to 'dlc1'
17:58:52.55   GAME -- Closing state 'dlc1'
17:58:52.56   Activating screen: GameHistory
17:58:52.56   GAME -- Setting campaign state to 'dlc1'
17:58:52.56   GAME -- Closing state 'dlc1'
17:58:52.57   GAME -- Setting campaign state to 'dlc2'
17:58:52.57   GAME -- Closing state 'dlc2'
17:58:52.57   GAME -- Setting campaign state to 'dlc3'
17:58:52.57   GAME -- Closing state 'dlc3'
17:58:52.57   Activating screen: OnlineGameSetup
17:58:52.57   Activating screen: RelicOnlineTabs
17:58:52.58   Activating screen: ScreenTipPopup
17:58:52.58   AutomatchInternal::OnHostComplete - Completed Host with success=1
17:58:52.58   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
17:58:52.58   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
17:58:52.59   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:58:52.59   Net::Session::SetVisible - session is set to VISIBLE.
17:58:52.59   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.459911, bars=5, max avg=0.047, sd=0.008, 5 samples =  0.05 0.04 0.04 0.06 0.05
17:58:52.60   Activating screen: ScreenTipPopup
17:58:52.60   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:58:52.66   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:59:01.05   Activating screen: MessageBoxPopup
17:59:01.05   Session is marking the local peer PeerID 1 for death
17:59:01.05   Session::GetEvent DestroyPeer success Peer 1 removed
17:59:01.05   Session::DestroyPeer - deallocating peer 1
17:59:01.05   Session - Requested Disconnect for connectionID 4
17:59:01.05   Deallocated route ID=5 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:59:01.05   Session - Requested Disconnect for connectionID 5
17:59:01.05   Closed route 5<>5 for PeerID 1 at WINaddr:7.19.158.52:6112;
17:59:01.05   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:59:01.05   Destroyed Matchinfo
17:59:01.08   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:59:01.08   PeerRemoveAll - flushing local session peer data
17:59:01.08   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:59:01.09   Activating screen: FEMovie
17:59:01.09   Activating screen: OnlineWidget
17:59:01.09   Activating screen: FE_mm_01
17:59:01.11   GAME -- Setting campaign state to 'dlc1'
17:59:01.11   GAME -- Closing state 'dlc1'
17:59:01.11   GAME -- Setting campaign state to 'dlc2'
17:59:01.11   GAME -- Closing state 'dlc2'
17:59:01.12   GAME -- Setting campaign state to 'dlc3'
17:59:01.12   GAME -- Closing state 'dlc3'
17:59:01.12   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:59:01.12   GAME -- Setting campaign state to 'dlc1'
17:59:01.12   GAME -- Closing state 'dlc1'
17:59:01.13   GAME -- Setting campaign state to 'dlc2'
17:59:01.13   GAME -- Closing state 'dlc2'
17:59:01.13   GAME -- Setting campaign state to 'dlc3'
17:59:01.13   GAME -- Closing state 'dlc3'
17:59:12.80   Activating screen: QuitPromptDialog
17:59:13.82   GameApp::Quit( exitValue = 0 )
17:59:13.84   GameApp::ShutFrontEnd
17:59:13.84   GAME -- Ending FE
17:59:13.84   UIFrontEnd - Unloading Front End
17:59:13.86   SOUND -- Shutting down ...
17:59:13.91   SOUND -- Shutdown completed!
17:59:13.93   ~FEAppListener - Flushing queued online notifications
17:59:13.94   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 1024.
17:59:14.45   DLLDriverLinker -- 2 DLL drivers found.
17:59:14.45   ActionDatabase : [2621] global actions, [0] dummy actions.
17:59:14.45   NetworkManager::Destroy - destroying network manager
17:59:14.45   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
17:59:14.45   MessageInternal::DestroyChannel: Destroyed channel 50525459
17:59:14.45   PartyInternal: Fade to black
17:59:14.45   AutomatchInternal: Fade to black
17:59:14.45   PeerRemoveAll - flushing local session peer data
17:59:14.45   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:59:14.45   MessageInternal::DestroyChannel: Destroyed channel 47535450
17:59:14.45   Session - Requested Disconnect for connectionID 1
17:59:14.45   SessionManager::UnregisterSession - Unregistering session 04361698
17:59:14.45   SessionManager::~SessionManager - uninitializing
17:59:14.46   Net::ThreadFunction - Returning from the network thread function...
17:59:14.47   Transport::~Transport - shutting down transport
17:59:14.47   Transport::~Connection - closing WINaddr:255.255.255.255:6112;,WINaddr:255.255.255.255:6112;
17:59:14.47   Transport::~Connection - closing WINaddr:7.19.158.52:6112;,WINaddr:7.19.158.52:6112;
17:59:14.47   Transport::~Connection - closing WINaddr:7.55.76.243:6112;,WINaddr:7.55.76.243:6112;
17:59:14.47   Transport::~Connection - closing WINaddr:7.53.0.239:6112;,WINaddr:7.53.0.239:6112;
17:59:14.47   Transport::~Connection - closing WINaddr:7.94.25.46:6112;,WINaddr:7.94.25.46:6112;
17:59:14.47   Transport::~Connection - closing WINaddr:7.37.7.123:6112;,WINaddr:7.37.7.123:6112;
17:59:14.47   Wrote 261 bytes to network datastore login_cache.bin
17:59:14.47   Wrote 52 bytes to network datastore global_cache.bin
17:59:14.47   Wrote 28 bytes to network datastore ping_cache.bin
17:59:14.47   Datastore -- uninitialize complete
17:59:14.47   QuazalInitializer - static uninit of Quazal library
17:59:14.47   QuazalInitializer - unhooking memory 0 currently allocated, 230 allocations, total of 22304 bytes
