-- Log file for all dbTracef messages --

20:45:15.15   NetworkManager::Create - creating network manager
20:45:15.15   Read 971 bytes from network datastore login_cache.bin
20:45:15.15   Read 135912 bytes from network datastore global_cache.bin
20:45:15.15   Read 365878 bytes from network datastore ping_cache.bin
20:45:15.15   QuazalInitializer - static initializing Quazal library
20:45:15.16   PingCache - populating cache with 13555 pings
20:45:15.28   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
20:45:15.29   WinTransport - CreateSocket exclusive broadcast socket was available.
20:45:15.29   WinTransport - CreateSocket listening for broadcasts on default port
20:45:15.30   WinTransport - Host Name: Brenky-PC, aliases: , type=AF_INET, len=4
20:45:15.30   WinTransport - Host IP Address #0: 192.168.1.10
20:45:15.30   WinTransport - Interface #0: ip:192.168.1.10, broadcast:192.168.1.10, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
20:45:15.30   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
20:45:15.30   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
20:45:15.30   WinTransport - Quazal address string = udp:/address=192.168.1.10;port=6112
20:45:15.30   SessionManager - Peer Header Size = 16 bytes
20:45:15.30   SessionManager - Game Data overhead = 7 bytes
20:45:15.30   SessionManager - Proxy overhead = 7 bytes
20:45:15.30   MessageInternal::CreateChannel: Created channel 47535450
20:45:15.30   Session::Initialize - info, initializing session object, using threads.
20:45:15.30   SessionManager::RegisterSession - Registering new session 07b70448
20:45:15.30   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
20:45:15.30   AutomatchInternal: Instantiating
20:45:15.30   PartyInternal: Instantiating
20:45:15.30   MessageInternal::CreateChannel: Created channel 50525459
20:45:15.30   MessageInternal::CreateChannel: Created channel 51434b4d
20:45:15.30   Net::ThreadFunction - Entering network thread function...
20:45:15.31   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
20:45:15.31   MessageInternal::CreateChannel: Created channel 534d5347
20:45:15.31   MessageInternal::CreateChannel: Created channel 474d4343
20:45:15.31   MessageInternal::CreateChannel: Created channel 474f424a
20:45:15.31   MessageInternal::CreateChannel: Created channel 4d4f444d
20:45:15.31   MessageInternal::CreateChannel: Created channel 53594e43
20:45:15.31   MessageInternal::DestroyChannel: Destroyed channel 534d5347
20:45:15.31   MessageInternal::DestroyChannel: Destroyed channel 474d4343
20:45:15.31   MessageInternal::DestroyChannel: Destroyed channel 474f424a
20:45:15.31   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
20:45:15.31   MessageInternal::DestroyChannel: Destroyed channel 53594e43
20:45:15.31   GAME -- Available memory: 8187MB Physical RAM, 8186MB Pagefile, 4095 Virtual Address Space
20:45:15.33   Transport - Largest received is now 19
20:45:15.33   Transport::OpenInternal request to WINaddr:192.168.1.10:6112;
20:45:16.58   DLLDriverLinker -- Adding driver 'spDx10.dll'.
20:45:16.59   DLLDriverLinker -- Adding driver 'spDx9.dll'.
20:45:16.59   DLLDriverLinker -- 2 DLL drivers found.
20:45:16.69   SPDx10 -- Adapter [AMD Radeon HD 6800 Series]: 1008MB dedicated video memory, 0MB dedicated system memory and 3837MB shared system memory.
20:45:18.24   DLLDriverLinker -- 2 DLL drivers found.
20:45:18.31   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
20:45:18.31   GAME -- Resolution set to 1920x1080 (fullscreen).
20:45:18.34   SPDx9 -- Driver Name = aticfx32.dll  Desc = AMD Radeon HD 6800 Series
20:45:18.34   SPDx9 -- Driver Vendor = 0x1002  Device = 0x6739  SubSys = 0x31101682  Rev = 0x0000
20:45:18.34   SPDx9 -- Driver Version  Product = 0x0008  Version = 0x0011  SubVersion = 0x00  Build = 11.69
20:45:18.34   SPDx9 -- Driver GUID = {D7B71EE2-2479-11CF-A371-1A11BEC2C535}
20:45:18.65   SPDx9 -- 722MB available Texture Memory
20:45:18.65   ShaderDatabase: using shader profile [ps30]
20:45:18.97   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
20:45:19.12   GameObjLoader 06feef20 - resetting counters
20:45:19.12   GameObjLoader 06feef20 - Created loader
20:45:19.12   GameObjLoader 06fef080 - resetting counters
20:45:19.12   GameObjLoader 06fef080 - Created loader
20:45:19.34   GAME -- Beginning FE
20:45:19.34   Sent message game CompanyOfHeroes started 5172 602 allowtraffic
20:45:19.34   RemoteDLManager - Connection Restored.
20:45:19.34   UIFrontEnd - Loading Front End
20:45:19.34   THREAD: Hyper-Threading Technology Processors are not detected.
20:45:19.46   SOUND -- Initializing ...
20:45:19.50   INNIMapDCA Key not found: sp_speechducker::time
20:45:19.82   SOUND -- Initialization completed!
20:45:19.82   UIFrontEnd - Initializing Forms
20:45:21.01   CampaignFilter::BindFilterSpecificWidgets()
20:45:21.12   Activating screen: MovieScreen
20:45:21.12   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
20:45:24.40   Activating screen: AppLoadingForm
20:45:24.40   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
20:45:25.79   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
20:45:25.79   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
20:45:25.79   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
20:45:25.79   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
20:45:25.79   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
20:45:25.79   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
20:45:26.01   GAME -- Using player profile NEO
20:45:26.43   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
20:45:26.57   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
20:45:26.97   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
20:45:26.97   RendezvousManager: CreateSession - starting profile=Guest login
20:45:28.36   RendezvousManager: Login complete and successfull
20:45:28.37   RendezvousManager initialized
20:45:28.54   Current server English:live version is 602.0, client is 602.0
20:45:28.56   OnConnect: successful connection established, enabling reconnect
20:45:28.56   OnConnect: this wasnt a reconnect, no need for autologin
20:45:28.57   Logging in Brenky88 on controller:0
20:45:29.04   Login completed: ACCOUNT_VALIDATED
20:45:29.04   Found 1 profiles for account Brenky88
20:45:29.04   Found profile: Brenky88
20:45:29.04   installed_products = ( DLC1 DLC2 DLC3 )
20:45:29.04   OnLogin: no previous login, auto selecting profile not required
20:45:29.07   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
20:45:29.08   CRC & Version Info : 0000025a:69b4e393:ec3c9e0d eastern_front:602:factionfix.dll 1
20:45:29.08   Activating screen: FEMovie
20:45:29.08   Activating screen: OnlineWidget
20:45:29.08   Activating screen: RelicOnlineProfileSelect
20:45:29.11   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
20:45:29.11   Activating screen: RelicOnlineWait
20:45:29.11   RendezvousManager - destroying chat handler
20:45:29.11   RendezvousManager: CreateSession - starting logout profile = 100:Guest
20:45:29.33   RendezvousManager: Logout complete
20:45:29.33   RendezvousManager - terminating all server calls in progress
20:45:29.33   CallManager - terminating all server calls in progress (1 in progress)
20:45:29.33   RendezvousManager: OnCredentialsEvent - starting profile login
20:45:30.64   RendezvousManager: Login complete and successfull
20:45:30.64   RendezvousManager - creating chat handler
20:45:30.65   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
20:45:30.89   QuazalSelectProfileAsync - Got UserID
20:45:30.92   Transport - Largest sent is now 16
20:45:31.06   Transport - Largest received is now 44
20:45:31.09   GetUserStats requested stats for PIDs ( 3750302 ) (best:0, full:1)
20:45:31.18   SelectProfileAsync - RegisterLocalURLs public [udp:/address=37.205.106.147;port=19934;PID=3750302;RVCID=80949169], private [udp:/address=192.168.1.10;port=6112;PID=3750302]
20:45:31.36   QuazalSelectProfileAsync - Got Full Stats
20:45:31.56   GetAutomatchMaps: Got [33] maps
20:45:31.66   PopulateArmyListBox - skipping race 2
20:45:31.66   PopulateArmyListBox - skipping race 0
20:45:31.67   PopulateArmyListBox - skipping race 1
20:45:31.67   PopulateArmyListBox - skipping race 3
20:45:31.67   AutoMatchForm::OnArmySelectionChanged - sending request info
20:45:31.67   GetMaxFrameTimeFromProfile: players=2 expected FPS=25.739973, bars=5, max avg=0.028, sd=0.011, 5 samples =  0.02 0.02 0.02 0.04 0.04
20:45:31.67   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
20:45:31.67   QuazalSelectProfileAsync - Got Automatch maps
20:45:47.16   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
20:45:47.41   Profile [00000000:0039399e] selected on controller#0
20:45:47.41   Activating screen: FEMovie
20:45:47.41   Activating screen: OnlineWidget
20:45:47.41   Activating screen: FE_mm_01
20:45:47.41   Activating screen: RelicOnlineWait
20:45:47.46   GAME -- Setting campaign state to 'dlc1'
20:45:47.46   GAME -- Closing state 'dlc1'
20:45:47.47   GAME -- Setting campaign state to 'dlc2'
20:45:47.47   GAME -- Closing state 'dlc2'
20:45:47.47   GAME -- Setting campaign state to 'dlc3'
20:45:47.47   GAME -- Closing state 'dlc3'
20:46:00.34   SPDx9 -- 718MB available Texture Memory
20:46:02.02   Activating screen: MessageBoxPopup
20:46:02.02   Created Matchinfo
20:46:02.02   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
20:46:02.02   starting online hosting
20:46:02.03   OnlineHostAsync: initiating CallCreateMatch
20:46:02.21   OnlineHostAsync: created gid=276531771
20:46:02.22   Transport - Largest sent is now 81
20:46:02.26   RendezvousNotifier - Received Participate ParticipationEvent.
20:46:02.37   Transport - Largest received is now 109
20:46:02.48   OnlineHostAsync - RegisterLocalURLs public [udp:/address=37.205.106.147;port=19934;PID=3750302;RVCID=80949169], private [udp:/address=192.168.1.10;port=6112;PID=3750302]
20:46:02.66   OnlineHostAsync: initiating UpdateSessionURL [gid=276531771, url=udp:/address=37.205.106.147;port=19934;PID=3750302;RVCID=80949169]
20:46:02.86   OnJoinAdvertisementSuccess - joined online match, server leave notification required
20:46:02.86   starting local hosting
20:46:02.86   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
20:46:02.86   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.1.10:6112;
20:46:02.86   Transport::OpenInternal request to WINaddr:192.168.1.10:6112;
20:46:02.86   Session::Host sid = 107B8A3B, hostURL = , local addresses = WINaddr:192.168.1.10:6112;
20:46:02.86   ValidateCustomData: called with 433 bytes of custom data
20:46:02.86   Host accepted Peer 1 into the match at address list=WINaddr:192.168.1.10:6112;, routes=WINaddr:192.168.1.10:6112;
20:46:02.86   AdvertisementInternal::Process - EVENT_NEWPEER
20:46:02.86   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
20:46:02.88   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
20:46:02.88   hosting - Session is connected
20:46:02.88   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:02.89   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:46:02.89   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
20:46:02.90   hosting completed successfully
20:46:02.90   HostAsync - completed with HostResult = 0
20:46:02.90   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
20:46:02.90   Activating screen: OnlineGameSetup
20:46:02.90   MessageInternal::CreateChannel: Created channel 534d5347
20:46:02.90   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:02.90   MatchInternal::SetMatchType - new type 14 - updating server
20:46:03.07   SetVisible called while !IsConnected
20:46:03.08   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:03.09   Activating screen: RelicOnlineChat
20:46:03.09   Activating screen: RelicOnlineNewsScreen
20:46:03.14   Activating screen: RelicOnlineStatsScreen
20:46:03.14   Activating screen: Achievements
20:46:03.14   GAME -- Setting campaign state to 'dlc1'
20:46:03.14   GAME -- Closing state 'dlc1'
20:46:03.14   GAME -- Setting campaign state to 'dlc2'
20:46:03.14   GAME -- Closing state 'dlc2'
20:46:03.14   GAME -- Setting campaign state to 'dlc3'
20:46:03.14   GAME -- Closing state 'dlc3'
20:46:03.14   GAME -- Setting campaign state to 'dlc1'
20:46:03.14   GAME -- Closing state 'dlc1'
20:46:03.15   Activating screen: GameHistory
20:46:03.15   GAME -- Setting campaign state to 'dlc1'
20:46:03.16   GAME -- Closing state 'dlc1'
20:46:03.16   GAME -- Setting campaign state to 'dlc2'
20:46:03.16   GAME -- Closing state 'dlc2'
20:46:03.16   GAME -- Setting campaign state to 'dlc3'
20:46:03.16   GAME -- Closing state 'dlc3'
20:46:03.16   Activating screen: OnlineGameSetup
20:46:03.16   Activating screen: RelicOnlineTabs
20:46:03.16   AutomatchInternal::OnHostComplete - Completed Host with success=1
20:46:03.16   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
20:46:03.16   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
20:46:03.17   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:03.17   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:03.18   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:03.24   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:03.29   GAME -- Setting campaign state to 'dlc1'
20:46:03.29   GAME -- Closing state 'dlc1'
20:46:03.29   GAME -- Setting campaign state to 'dlc2'
20:46:03.29   GAME -- Closing state 'dlc2'
20:46:03.29   GAME -- Setting campaign state to 'dlc3'
20:46:03.29   GAME -- Closing state 'dlc3'
20:46:03.30   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:03.30   Sending encoded message 1448 bytes, to UserID [00000000:001f797c]
20:46:03.30   Sending encoded message 1448 bytes, to UserID [00000000:0031a623]
20:46:03.30   Sending encoded message 1448 bytes, to UserID [00000000:003f5ad0]
20:46:03.36   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:04.11   Requesting Relic Downloader soft throttle call [GetNewsAsync] has time 811
20:46:04.11   Sent message game CompanyOfHeroes softthrottle
20:46:04.16   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:46:04.49   Activating screen: MessageBoxPopup
20:46:04.50   Session is marking the local peer PeerID 1 for death
20:46:04.50   OnlineLeaveAdvertisementAsync: Call to LeaveMatch id = 276531771, cancel = 1, badPids = 0, matchState = 0
20:46:04.50   Session::GetEvent DestroyPeer success Peer 1 removed
20:46:04.50   Session::DestroyPeer - deallocating peer 1
20:46:04.50   Session - Requested Disconnect for connectionID 2
20:46:04.50   Deallocated route ID=3 for PeerID 1 at WINaddr:192.168.1.10:6112;
20:46:04.50   Session - Requested Disconnect for connectionID 3
20:46:04.50   Closed route 3<>3 for PeerID 1 at WINaddr:192.168.1.10:6112;
20:46:04.50   AdvertisementInternal::Process - EVENT_DESTROYPEER
20:46:04.50   LeaveMatchInternal - no action taken, needLeave=0, service=0
20:46:04.50   Destroyed Matchinfo
20:46:05.31   Requesting Relic Downloader hard throttle call [GetAdvertisementsAsync] has time 2003
20:46:05.31   Sent message game CompanyOfHeroes hardthrottle
20:46:05.32   Got dlman msg [ack game CompanyOfHeroes hardthrottle]
20:46:05.41   Requesting Relic Downloader soft throttle call [GetUserStatsAsync] has time 1762
20:46:05.41   Sent message game CompanyOfHeroes softthrottle
20:46:05.42   QueryMatches: Got [59] maps, [187] ids, [17] advertisements, startID [1]
20:46:05.42   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
20:46:05.42   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 170 matches
20:46:05.42   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:46:05.52   GetUserStats requested stats for PIDs ( 124754 348221 373818 405537 492127 597806 1539898 1785931 1901044 2030928 2062716 2101034 2389532 2435188 2442054 2490804 2490837 2500531 2801628 2927135 2975581 3083365 3121448 3123889 3174249 3192743 3197396 3253795 3319735 3465129 3564657 3569135 3570007 3575815 3601377 3612546 3612734 3629819 3630221 3640650 3664920 3670099 3670796 3739283 3742574 3748674 3864222 3864996 3953069 4044131 4152016 4187249 4196606 4210502 4695299 4734486 4740943 4782524 4879942 4886941 ) (best:60, full:0)
20:46:05.61   PeerRemoveAll - flushing local session peer data
20:46:05.61   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
20:46:05.62   Activating screen: FEMovie
20:46:05.62   Activating screen: OnlineWidget
20:46:05.62   Activating screen: FE_mm_01
20:46:05.63   GAME -- Setting campaign state to 'dlc1'
20:46:05.63   GAME -- Closing state 'dlc1'
20:46:05.64   GAME -- Setting campaign state to 'dlc2'
20:46:05.64   GAME -- Closing state 'dlc2'
20:46:05.64   GAME -- Setting campaign state to 'dlc3'
20:46:05.64   GAME -- Closing state 'dlc3'
20:46:05.64   MessageInternal::DestroyChannel: Destroyed channel 534d5347
20:46:05.64   GAME -- Setting campaign state to 'dlc1'
20:46:05.64   GAME -- Closing state 'dlc1'
20:46:05.64   GAME -- Setting campaign state to 'dlc2'
20:46:05.64   GAME -- Closing state 'dlc2'
20:46:05.65   GAME -- Setting campaign state to 'dlc3'
20:46:05.65   GAME -- Closing state 'dlc3'
20:46:06.28   Activating screen: OptionsMenu
20:46:06.28   THREAD: Hyper-Threading Technology Processors are not detected.
20:46:06.30   THREAD: Hyper-Threading Technology Processors are not detected.
20:46:08.68   QueryMatches: Got [59] maps, [191] ids, [17] advertisements, startID [276529041]
20:46:08.68   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 157 matches
20:46:10.18   Activating screen: FE_mm_01
20:46:10.18   GAME -- Setting campaign state to 'dlc1'
20:46:10.18   GAME -- Closing state 'dlc1'
20:46:10.19   GAME -- Setting campaign state to 'dlc2'
20:46:10.19   GAME -- Closing state 'dlc2'
20:46:10.19   GAME -- Setting campaign state to 'dlc3'
20:46:10.19   GAME -- Closing state 'dlc3'
20:46:10.90   Activating screen: MessageBoxPopup
20:46:10.90   Created Matchinfo
20:46:10.90   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
20:46:10.90   starting online hosting
20:46:10.92   OnlineHostAsync: initiating CallCreateMatch
20:46:11.12   OnlineHostAsync: created gid=276531799
20:46:11.15   RendezvousNotifier - Received Participate ParticipationEvent.
20:46:11.38   OnlineHostAsync - RegisterLocalURLs public [udp:/address=37.205.106.147;port=19934;PID=3750302;RVCID=80949169], private [udp:/address=192.168.1.10;port=6112;PID=3750302]
20:46:11.55   OnlineHostAsync: initiating UpdateSessionURL [gid=276531799, url=udp:/address=37.205.106.147;port=19934;PID=3750302;RVCID=80949169]
20:46:11.75   OnJoinAdvertisementSuccess - joined online match, server leave notification required
20:46:11.75   starting local hosting
20:46:11.75   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
20:46:11.75   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.1.10:6112;
20:46:11.75   Transport::OpenInternal request to WINaddr:192.168.1.10:6112;
20:46:11.75   Session::Host sid = 107B8A57, hostURL = , local addresses = WINaddr:192.168.1.10:6112;
20:46:11.75   ValidateCustomData: called with 433 bytes of custom data
20:46:11.75   Host accepted Peer 1 into the match at address list=WINaddr:192.168.1.10:6112;, routes=WINaddr:192.168.1.10:6112;
20:46:11.75   AdvertisementInternal::Process - EVENT_NEWPEER
20:46:11.75   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
20:46:11.77   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
20:46:11.77   hosting - Session is connected
20:46:11.77   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:11.77   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:46:11.77   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
20:46:11.78   hosting completed successfully
20:46:11.78   HostAsync - completed with HostResult = 0
20:46:11.78   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
20:46:11.78   Activating screen: OnlineGameSetup
20:46:11.78   MessageInternal::CreateChannel: Created channel 534d5347
20:46:11.79   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:11.79   MatchInternal::SetMatchType - new type 14 - updating server
20:46:11.96   SetVisible called while !IsConnected
20:46:11.97   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:11.98   Activating screen: RelicOnlineChat
20:46:11.98   Activating screen: RelicOnlineNewsScreen
20:46:12.03   Activating screen: RelicOnlineStatsScreen
20:46:12.03   Activating screen: Achievements
20:46:12.03   GAME -- Setting campaign state to 'dlc1'
20:46:12.03   GAME -- Closing state 'dlc1'
20:46:12.03   GAME -- Setting campaign state to 'dlc2'
20:46:12.03   GAME -- Closing state 'dlc2'
20:46:12.03   GAME -- Setting campaign state to 'dlc3'
20:46:12.03   GAME -- Closing state 'dlc3'
20:46:12.03   GAME -- Setting campaign state to 'dlc1'
20:46:12.03   GAME -- Closing state 'dlc1'
20:46:12.04   Activating screen: GameHistory
20:46:12.04   GAME -- Setting campaign state to 'dlc1'
20:46:12.04   GAME -- Closing state 'dlc1'
20:46:12.05   GAME -- Setting campaign state to 'dlc2'
20:46:12.05   GAME -- Closing state 'dlc2'
20:46:12.05   GAME -- Setting campaign state to 'dlc3'
20:46:12.05   GAME -- Closing state 'dlc3'
20:46:12.05   Activating screen: OnlineGameSetup
20:46:12.05   Activating screen: RelicOnlineTabs
20:46:12.05   AutomatchInternal::OnHostComplete - Completed Host with success=1
20:46:12.05   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
20:46:12.05   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
20:46:12.06   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:12.06   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:12.07   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:12.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:12.12   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:12.12   Sending encoded message 1448 bytes, to UserID [00000000:001f797c]
20:46:12.12   Sending encoded message 1448 bytes, to UserID [00000000:0031a623]
20:46:12.12   Sending encoded message 1448 bytes, to UserID [00000000:003f5ad0]
20:46:12.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:13.18   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:13.23   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:13.91   QueryMatches: Got [60] maps, [191] ids, [17] advertisements, startID [276530112]
20:46:13.92   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 140 matches
20:46:16.01   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.1/0.3, max unsent 0, version err 0, merge 0
20:46:17.15   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:17.15   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:17.15   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:17.18   TGAIO -- TGA file 'Data:scenarios\mp\groves palace_map_base.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
20:46:17.19   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:17.24   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:17.28   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:18.09   Requesting Relic Downloader soft throttle call [OnlineUpdateAdvertisementAsync] has time 845
20:46:18.09   Sent message game CompanyOfHeroes softthrottle
20:46:18.14   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:46:18.94   QueryMatches: Got [62] maps, [197] ids, [17] advertisements, startID [276530601]
20:46:18.94   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 130 matches
20:46:19.13   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:19.13   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:19.14   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:19.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:19.20   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:19.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:21.02   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:21.02   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:21.03   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:21.05   TGAIO -- TGA file 'Data:scenarios\mp\groves palace_map_slots.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
20:46:21.06   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:21.11   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:21.14   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:21.75   Activating screen: DynamicPopupMenu
20:46:22.51   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:22.51   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:22.51   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:22.53   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:22.58   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:22.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:22.83   Activating screen: DynamicPopupMenu
20:46:23.30   Activating screen: DynamicPopupMenu
20:46:23.75   QueryMatches: Got [61] maps, [198] ids, [17] advertisements, startID [276530871]
20:46:23.75   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 116 matches
20:46:24.14   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:24.14   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:24.14   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:24.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:24.20   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:24.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:24.86   Activating screen: RaceSelectionPopup
20:46:25.65   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:25.65   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:25.65   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:25.67   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:25.72   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:25.74   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:26.75   Activating screen: RaceSelectionPopup
20:46:27.38   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:46:27.38   Net::Session::SetVisible - session is set to INVISIBLE.
20:46:27.39   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:46:27.41   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:27.45   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:46:27.48   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:46:28.77   QueryMatches: Got [63] maps, [201] ids, [17] advertisements, startID [276531098]
20:46:28.77   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 102 matches
20:46:33.79   QueryMatches: Got [63] maps, [204] ids, [17] advertisements, startID [276531257]
20:46:33.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 89 matches
20:46:38.88   QueryMatches: Got [63] maps, [206] ids, [17] advertisements, startID [276531435]
20:46:38.89   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 75 matches
20:46:38.92   Transport - Largest sent is now 82
20:46:43.95   QueryMatches: Got [63] maps, [211] ids, [17] advertisements, startID [276531556]
20:46:43.95   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 65 matches
20:46:48.79   QueryMatches: Got [63] maps, [211] ids, [17] advertisements, startID [276531648]
20:46:48.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 48 matches
20:46:53.80   QueryMatches: Got [60] maps, [207] ids, [17] advertisements, startID [276531739]
20:46:53.81   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
20:46:58.69   QueryMatches: Got [61] maps, [208] ids, [17] advertisements, startID [276531808]
20:46:58.69   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches
20:47:03.69   QueryMatches: Got [60] maps, [209] ids, [17] advertisements, startID [276531886]
20:47:03.69   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 8 matches
20:47:06.68   Transport::OpenInternal request to WINaddr:192.168.1.20:6112;
20:47:08.68   QueryMatches: Got [58] maps, [207] ids, [17] advertisements, startID [276531955]
20:47:08.69   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 2 matches
20:47:12.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:47:12.00   MessageCounts: inval=0/0, seek=0/2, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=3/3, frag=0/0, Errors=0/0
20:47:13.85   QueryMatches: Got [62] maps, [206] ids, [17] advertisements, startID [276521326]
20:47:13.86   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 8 matches
20:47:17.01   Transport - median kBPS [hi/cur] sent = 1.0/0.1, recvd = 0.8/0.1, #p/sec[s/r] = 2.9/2.4, max unsent 0, version err 0, merge 1
20:47:18.94   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 807
20:47:18.94   Sent message game CompanyOfHeroes softthrottle
20:47:19.03   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:47:19.12   QueryMatches: Got [65] maps, [207] ids, [17] advertisements, startID [276529914]
20:47:19.13   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 12 matches
20:47:23.89   QueryMatches: Got [65] maps, [205] ids, [17] advertisements, startID [276530621]
20:47:23.90   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 15 matches
20:47:28.91   QueryMatches: Got [64] maps, [207] ids, [17] advertisements, startID [276530955]
20:47:28.91   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 25 matches
20:47:31.55   SPDx9 -- 718MB available Texture Memory
20:47:33.96   QueryMatches: Got [63] maps, [210] ids, [17] advertisements, startID [276531172]
20:47:33.96   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 29 matches
20:47:38.73   QueryMatches: Got [60] maps, [210] ids, [17] advertisements, startID [276531398]
20:47:38.74   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 36 matches
20:47:43.73   QueryMatches: Got [57] maps, [211] ids, [17] advertisements, startID [276531545]
20:47:43.73   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 42 matches
20:47:48.73   QueryMatches: Got [64] maps, [213] ids, [17] advertisements, startID [276531699]
20:47:48.73   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 45 matches
20:47:53.78   QueryMatches: Got [66] maps, [219] ids, [17] advertisements, startID [276531785]
20:47:53.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 53 matches
20:47:58.73   QueryMatches: Got [65] maps, [222] ids, [17] advertisements, startID [276531886]
20:47:58.73   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 57 matches
20:48:03.67   QueryMatches: Got [69] maps, [219] ids, [17] advertisements, startID [276532002]
20:48:03.67   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 44 matches
20:48:08.62   QueryMatches: Got [68] maps, [215] ids, [17] advertisements, startID [276532089]
20:48:08.63   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 26 matches
20:48:13.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:48:13.01   MessageCounts: inval=0/0, seek=0/14, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=14/19, frag=0/0, Errors=0/0
20:48:13.62   QueryMatches: Got [69] maps, [222] ids, [17] advertisements, startID [276532125]
20:48:13.62   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 18 matches
20:48:18.01   Transport - median kBPS [hi/cur] sent = 1.0/0.2, recvd = 0.8/0.2, #p/sec[s/r] = 2.9/2.9, max unsent 0, version err 0, merge 0
20:48:18.58   QueryMatches: Got [70] maps, [223] ids, [17] advertisements, startID [276532161]
20:48:18.59   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 7 matches
20:48:23.63   QueryMatches: Got [66] maps, [221] ids, [17] advertisements, startID [276532211]
20:48:23.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
20:48:28.77   QueryMatches: Got [68] maps, [217] ids, [17] advertisements, startID [276521326]
20:48:28.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 5 matches
20:48:33.91   QueryMatches: Got [65] maps, [217] ids, [17] advertisements, startID [276529933]
20:48:33.91   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 8 matches
20:48:38.78   QueryMatches: Got [66] maps, [210] ids, [17] advertisements, startID [276530730]
20:48:38.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 15 matches
20:48:43.76   QueryMatches: Got [64] maps, [210] ids, [17] advertisements, startID [276531119]
20:48:43.77   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 22 matches
20:48:48.74   QueryMatches: Got [63] maps, [203] ids, [17] advertisements, startID [276531423]
20:48:48.75   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 21 matches
20:48:53.59   QueryMatches: Got [62] maps, [199] ids, [17] advertisements, startID [276531611]
20:48:53.60   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 28 matches
20:48:58.78   QueryMatches: Got [63] maps, [199] ids, [17] advertisements, startID [276531760]
20:48:58.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 29 matches
20:49:03.82   QueryMatches: Got [63] maps, [200] ids, [17] advertisements, startID [276531910]
20:49:03.83   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 35 matches
20:49:08.64   QueryMatches: Got [65] maps, [199] ids, [17] advertisements, startID [276532079]
20:49:08.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 39 matches
20:49:09.35   RNT_ReportPing: user 5245634:IVANUSHKANAIDENOV CANNOT connect pingMS=65535
20:49:13.88   QueryMatches: Got [62] maps, [197] ids, [17] advertisements, startID [276532159]
20:49:13.88   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 42 matches
20:49:14.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:49:14.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=10/0, frag=0/0, Errors=0/0
20:49:18.71   QueryMatches: Got [62] maps, [194] ids, [17] advertisements, startID [276532255]
20:49:18.72   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 26 matches
20:49:19.01   Transport - median kBPS [hi/cur] sent = 1.0/0.1, recvd = 0.8/0.1, #p/sec[s/r] = 3.5/2.7, max unsent 0, version err 0, merge 0
20:49:23.63   QueryMatches: Got [64] maps, [194] ids, [17] advertisements, startID [276532336]
20:49:23.63   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 16 matches
20:49:28.59   QueryMatches: Got [65] maps, [192] ids, [17] advertisements, startID [276532415]
20:49:28.60   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 6 matches
20:49:33.61   QueryMatches: Got [67] maps, [198] ids, [17] advertisements, startID [276532463]
20:49:33.61   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 4 matches
20:49:38.87   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 802
20:49:38.87   Sent message game CompanyOfHeroes softthrottle
20:49:38.91   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:49:39.00   QueryMatches: Got [65] maps, [196] ids, [17] advertisements, startID [276527330]
20:49:39.01   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 9 matches
20:49:43.90   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 811
20:49:43.90   Sent message game CompanyOfHeroes softthrottle
20:49:43.94   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:49:45.10   Requesting Relic Downloader hard throttle call [GetAdvertisementsAsync] has time 2011
20:49:45.10   Sent message game CompanyOfHeroes hardthrottle
20:49:45.14   Got dlman msg [ack game CompanyOfHeroes hardthrottle]
20:49:45.28   QueryMatches: Got [64] maps, [194] ids, [17] advertisements, startID [276530570]
20:49:45.28   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches
20:49:48.78   QueryMatches: Got [62] maps, [195] ids, [17] advertisements, startID [276531031]
20:49:48.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 22 matches
20:49:53.74   QueryMatches: Got [59] maps, [192] ids, [17] advertisements, startID [276531490]
20:49:53.74   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 22 matches
20:49:58.78   QueryMatches: Got [61] maps, [193] ids, [17] advertisements, startID [276531715]
20:49:58.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 24 matches
20:50:03.82   QueryMatches: Got [59] maps, [188] ids, [17] advertisements, startID [276531910]
20:50:03.83   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 22 matches
20:50:08.77   QueryMatches: Got [59] maps, [189] ids, [17] advertisements, startID [276532084]
20:50:08.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 23 matches
20:50:13.90   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 840
20:50:13.90   Sent message game CompanyOfHeroes softthrottle
20:50:13.94   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:50:14.26   QueryMatches: Got [58] maps, [191] ids, [17] advertisements, startID [276532255]
20:50:14.27   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
20:50:15.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:50:15.00   MessageCounts: inval=0/0, seek=0/7, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=13/11, frag=0/0, Errors=0/0
20:50:18.59   QueryMatches: Got [58] maps, [191] ids, [17] advertisements, startID [276532388]
20:50:18.59   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 33 matches
20:50:20.01   Transport - median kBPS [hi/cur] sent = 1.0/0.3, recvd = 0.8/0.3, #p/sec[s/r] = 2.5/2.3, max unsent 0, version err 0, merge 1
20:50:23.59   QueryMatches: Got [59] maps, [196] ids, [17] advertisements, startID [276532472]
20:50:23.60   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 29 matches
20:50:28.60   QueryMatches: Got [59] maps, [195] ids, [17] advertisements, startID [276532552]
20:50:28.60   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches
20:50:33.60   QueryMatches: Got [61] maps, [198] ids, [17] advertisements, startID [276532628]
20:50:33.61   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 5 matches
20:50:33.97   RendezvousNotifier - Received JoinRequest Event 3 (pid=3748674, p1=0, p2=0, str=).
20:50:34.11   ReplyToInvitationAsync: Call started.
20:50:34.20   ExtendInvitationAsync: Call started.
20:50:38.13   RendezvousNotifier - Received Participate ParticipationEvent.
20:50:38.65   QueryMatches: Got [62] maps, [198] ids, [17] advertisements, startID [276532680]
20:50:38.66   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
20:50:42.45   Transport - Largest received is now 365
20:50:42.45   Session - rcv'd Join request - address WINaddr:192.168.1.20:6112; not currently in use
20:50:42.45   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
20:50:42.45   Session::CheckAddress - initiating address check for WINaddr:192.168.1.20:6112;
20:50:42.45   Allocated route ID=0 for PeerID 2 at WINaddr:192.168.1.20:6112;
20:50:42.45   Transport::OpenInternal request to WINaddr:192.168.1.20:6112;
20:50:42.45   Session::CheckAddressSuccess - peer located at address WINaddr:192.168.1.20:6112;
20:50:42.45   Peer::AcceptRoute - accepted route 7<>5 WINaddr:192.168.1.20:6112; for peerID:2
20:50:42.45   Session - generating NewPeerAcceptedEventEx for peer 2@WINaddr:192.168.1.20:6112;
20:50:42.45   ValidateCustomData: called with 484 bytes of custom data
20:50:42.45   Host accepted Peer 2 into the match at address list=WINaddr:192.168.1.20:6112;, routes=WINaddr:192.168.1.20:6112;
20:50:42.45   AdvertisementInternal::Process - EVENT_NEWPEER
20:50:42.45   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:50:42.45   Net::Session::SetVisible - session is set to INVISIBLE.
20:50:42.47   Transport - Largest sent is now 1273
20:50:42.47   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:50:42.47   Net::Session::SetVisible - session is set to INVISIBLE.
20:50:42.47   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:50:42.49   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:42.54   Station [0:2] acknowledged matchinfo change #75
20:50:42.57   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:42.63   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:50:42.65   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:50:42.65   Net::Session::SetVisible - session is set to INVISIBLE.
20:50:42.65   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:50:42.67   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:42.75   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:42.80   Transport - Largest received is now 396
20:50:42.81   Station [0:2] acknowledged matchinfo change #77
20:50:42.81   Station [0:2] acknowledged matchinfo change #79
20:50:42.82   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:42.91   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:43.07   Station [0:2] acknowledged matchinfo change #81
20:50:43.07   Station [0:2] acknowledged matchinfo change #84
20:50:43.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:43.76   QueryMatches: Got [59] maps, [200] ids, [17] advertisements, startID [276528298]
20:50:43.76   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 13 matches
20:50:47.82   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:50:47.82   Net::Session::SetVisible - session is set to INVISIBLE.
20:50:47.83   GetMaxFrameTimeFromProfile: players=4 expected FPS=10.059857, bars=2, max avg=0.072, sd=0.027, 5 samples =  0.05 0.10 0.11 0.05 0.05
20:50:47.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:47.90   Station [0:2] acknowledged matchinfo change #85
20:50:47.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:47.98   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:50:48.01   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:48.07   Station [0:2] acknowledged matchinfo change #87
20:50:48.07   Station [0:2] acknowledged matchinfo change #90
20:50:48.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:49.02   QueryMatches: Got [63] maps, [203] ids, [17] advertisements, startID [276530638]
20:50:49.03   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches
20:50:53.79   QueryMatches: Got [63] maps, [203] ids, [17] advertisements, startID [276531178]
20:50:53.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 26 matches
20:50:53.80   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:50:53.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:50:53.81   Net::Session::SetVisible - session is set to INVISIBLE.
20:50:58.79   QueryMatches: Got [63] maps, [202] ids, [17] advertisements, startID [276531632]
20:50:58.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 29 matches
20:51:02.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:51:02.20   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
20:51:02.20   Station [0:2] acknowledged matchinfo change #91
20:51:02.20   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
20:51:02.20   Net::Session::SetVisible - session is set to INVISIBLE.
20:51:02.20   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:51:02.21   MatchInternal::SetMatchState - state 1 - updating server
20:51:02.21   Net::Session::SetVisible - session is set to INVISIBLE.
20:51:02.21   MatchSetup: Sending start game message!
20:51:02.21   GameSetupForm - Starting game
20:51:02.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
20:51:02.21   PopulateGameInfo - random seed:[1357588262], guid:[{ccf21b52-4619-4b74-b46e-94c57b6c69ee}], sync level:[0]
20:51:02.25   Error loading [DATA:levelingCurve.lua]
20:51:02.25   Error loading [DATA:levelingCurve.lua]
20:51:02.25   Error loading [DATA:levelingCurve.lua]
20:51:02.25   Error loading [DATA:levelingCurve.lua]
20:51:02.25   MOD - Setting player (0) race to: allies_commonwealth
20:51:02.25   MOD - Setting player (0) race to: 0
20:51:02.25   MOD - Setting player (1) race to: allies_soviets
20:51:02.25   MOD - Setting player (1) race to: 2
20:51:02.25   MOD - Setting player (2) race to: axis
20:51:02.25   MOD - Setting player (2) race to: 3
20:51:02.25   MOD - Setting player (3) race to: axis_panzer_elite
20:51:02.25   MOD - Setting player (3) race to: 4
20:51:02.30   OnlineUpdateStateAsync: initiating state change, id = 276531799, state=2
20:51:02.31   APP -- Game Start
20:51:02.31   Sent message game CompanyOfHeroes disallowtraffic
20:51:02.32   GAME -- Setting campaign state to 'dlc1'
20:51:02.32   GAME -- Closing state 'dlc1'
20:51:02.32   GAME -- Setting campaign state to 'dlc2'
20:51:02.32   GAME -- Closing state 'dlc2'
20:51:02.32   GAME -- Setting campaign state to 'dlc3'
20:51:02.32   GAME -- Closing state 'dlc3'
20:51:02.32   MessageInternal::DestroyChannel: Destroyed channel 534d5347
20:51:02.34   GAME -- Ending FE
20:51:02.34   UIFrontEnd - Unloading Front End
20:51:02.36   SOUND -- Shutting down ...
20:51:02.40   SOUND -- Shutdown completed!
20:51:02.41   MessageInternal::CreateChannel: Created channel 53594e43
20:51:02.41   P2PNetworkSynchronization::RegisterPeer - info, registered local station [1].
20:51:02.41   P2PNetworkSynchronization::RegisterPeer - info, registered remote station [2].
20:51:02.41   MessageInternal::CreateChannel: Created channel 474f424a
20:51:02.41
20:51:02.41   GAME -- *** Beginning mission GROVES PALACE (2 Humans, 2 Computers) ***
20:51:02.41
20:51:02.41   GameObj::StartGameObj - info, network session GUID set to [{ccf21b52-4619-4b74-b46e-94c57b6c69ee}].
20:51:02.41   GAME -- Recording game
20:51:02.47   Activating screen: GameLoadScreen
20:51:02.48   Got dlman msg [ack game CompanyOfHeroes disallowtraffic]
20:51:02.48   Station [0:2] acknowledged matchinfo change #92
20:51:02.48   GameObjController - OnMatchEvent: event type 6
20:51:02.49   TGAIO -- TGA file 'Data:scenarios\mp\groves palace.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
20:51:02.54   OnlineUpdateStateAsync: updated state for gid=276531799
20:51:02.62   THREAD: Hyper-Threading Technology Processors are not detected.
20:51:02.69   SOUND -- Initializing ...
20:51:03.21   SOUND -- Initialization completed!
20:51:03.23   PHYSICS: detected processor(s) capable of handling 4 threads.
20:51:03.26   MOD -- Locating MOD for scenario 'DATA:Scenarios\MP\GROVES PALACE'
20:51:03.26   MOD -- Using Mod 'Eastern_Front'
20:51:03.27   Unable to load/parse precache file [DATA:Scenarios\MP\GROVES PALACE_precache.lua]
20:51:03.37   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_force_beyond_reckoning_lower_load.smf'.  Expensive operation
20:51:03.37   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_force_beyond_reckoning_lower_load.smf'.  Expensive operation
20:51:04.81   QueryMatches: Got [63] maps, [204] ids, [17] advertisements, startID [276531881]
20:51:04.81   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 36 matches
20:51:04.96   GameObjLoader - upgrading load_count from 0 to 1080
20:51:07.27   GameObjLoader - upgrading load_count from 1080 to 1801
20:51:07.28   PHYSICS -- Created node factory 'HVOK'
20:51:07.28   PHYSICS -- Created node factory 'DMMY'
20:51:09.12   QueryMatches: Got [60] maps, [200] ids, [17] advertisements, startID [276532079]
20:51:09.13   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 32 matches
20:51:13.86   SPDx9 -- 714MB available Texture Memory.  Unable to create 2x2 texture.
20:51:13.99   GameObjLoader - upgrading load_count from 1588 to 4576
20:51:14.15   QueryMatches: Got [60] maps, [203] ids, [17] advertisements, startID [276532326]
20:51:14.15   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 37 matches
20:51:16.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:51:16.01              PeerID 2 CONN ack=  0 ( 83ms~0) unack=  0, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=11ms) 96.72%, pending=0, dead=0
20:51:16.01   MessageCounts: inval=0/0, seek=0/6, join=0/1, integ=0/0, seek_reply=0/0, join_reply=0/0, add=2/0, remove=0/0, drop=0/0, data=57/49, voice=0/0, rchk=0/0, nudge=5/0, peerhdr=0/0, proxy=0/0, ping=15/16, frag=0/0, Errors=0/0
20:51:17.85   GameObjLoader 06feef20 - resetting counters
20:51:17.85   GameObjLoader 06feef20 - LOAD_DONE
20:51:17.85   GAME - SessionSetup
20:51:17.85   GAME -- Can't initialize scenario pack more than once.
20:51:17.94   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
20:51:17.94   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
20:51:17.94   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
20:51:18.52   GAME - CreateGEWorld in 663 ms
20:51:18.52   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
20:51:18.72   GAME - SessionSetup finished in 863 ms
20:51:18.72   GAME - WaterReflectionManagerSetup
20:51:18.72   GAME - WaterReflectionManagerSetup finished in 0 ms
20:51:18.97   MessageInternal::CreateChannel: Created channel 474d4343
20:51:18.98   Regenerating ImpassMap data...
20:51:18.98       Impass Data was already valid, but regenerating...
20:51:19.11   Generating CanBuild Map.  THIS SHOULD ONLY HAPPEN IN WORLDBUILDER!  IF YOU SEE THIS IN GAME, RE-SAVE THE MAP!
20:51:19.11   Regenerating CanBuildMap data...
20:51:19.11   Generating CanShoot Map.
20:51:19.11   Pathfinder::Regenerate()...
20:51:19.15   Generating PathSectorMap...
20:51:19.37   Pathfinder::Regenerate() Done.
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x06939680
[Cope FactionFix] - patched @0x67695491
[Cope FactionFix] - hooked @0x67695492
[Cope FactionFix] - patched @0x67694a07
[Cope FactionFix] - hooked @0x67694a07
[Cope FactionFix] - Hook installed
20:51:19.48   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilate.scar] succeeded.
20:51:19.65   MOD -- Player  (unused player) (frame 0) (KillPlayer)
20:51:19.65   MOD -- Player  (unused player) (frame 0) (KillPlayer)
20:51:19.65   MOD -- Player  (unused player) (frame 0) (KillPlayer)
20:51:19.65   MOD -- Player  (unused player) (frame 0) (KillPlayer)
20:51:20.27   MessageInternal::CreateChannel: Created channel 4d4f444d
20:51:20.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
20:51:20.84   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
20:51:20.84   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
20:51:20.84   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
20:51:20.84   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
20:51:21.00   Transport - median kBPS [hi/cur] sent = 2.3/0.1, recvd = 0.9/0.2, #p/sec[s/r] = 4.9/4.4, max unsent 0, version err 0, merge 6
20:51:21.25   SPEECHMANAGER -- Loaded in 0.383478 seconds
20:51:21.81   QueryMatches: Got [61] maps, [210] ids, [17] advertisements, startID [276532478]
20:51:21.82   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 46 matches
20:51:22.08   GameObjLoader - upgrading load_count from 0 to 292
20:51:22.68   GameObjLoader 06fef080 - resetting counters
20:51:22.68   GameObjLoader 06fef080 - LOAD_DONE
20:51:23.47   PreloadResources took 727ms.
20:51:23.53   GAME -- Loading completed (21 seconds)
20:51:23.53   SIM -- Setting SyncErrorChecking level to Low
20:51:23.54   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:24.13   QueryMatches: Got [63] maps, [212] ids, [17] advertisements, startID [276532615]
20:51:24.13   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 51 matches
20:51:25.01   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:27.01   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:28.53   QueryMatches: Got [65] maps, [214] ids, [17] advertisements, startID [276532668]
20:51:28.53   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 43 matches
20:51:29.01   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:31.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:33.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:33.53   QueryMatches: Got [61] maps, [208] ids, [17] advertisements, startID [276532736]
20:51:33.53   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 26 matches
20:51:35.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:37.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:38.81   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 802
20:51:38.81   Sent message game CompanyOfHeroes softthrottle
20:51:38.83   Got dlman msg [ack game CompanyOfHeroes softthrottle]
20:51:38.88   QueryMatches: Got [63] maps, [208] ids, [17] advertisements, startID [276532810]
20:51:38.88   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 12 matches
20:51:39.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:41.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:43.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:43.51   QueryMatches: Got [65] maps, [210] ids, [17] advertisements, startID [276532867]
20:51:43.52   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 2 matches
20:51:45.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:47.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:48.52   QueryMatches: Got [62] maps, [208] ids, [17] advertisements, startID [276484494]
20:51:48.52   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 8 matches
20:51:49.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:51.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:53.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:53.68   QueryMatches: Got [61] maps, [208] ids, [17] advertisements, startID [276530159]
20:51:53.69   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 13 matches
20:51:55.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:57.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:51:58.82   QueryMatches: Got [65] maps, [210] ids, [17] advertisements, startID [276531119]
20:51:58.82   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 18 matches
20:51:59.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:3750302, Brenky88]" finished loading with checksum [574327454].
20:52:00.23   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:3748674, Neox88]" finished loading with checksum [574327454].
20:52:00.25   Activating screen: GameScreen
20:52:00.25   Activating screen: Decorators_widescreen
20:52:00.25   Activating screen: Taskbar_widescreen
20:52:00.25   Activating screen: SubtitleScreen
20:52:00.25   Activating screen: TextOverlayScreen
20:52:00.36   PerformanceRecorder::StartRecording for game size 4
20:52:00.36   GAME -- Starting mission...
20:52:00.94   We do not have a sync command for peer=2, currentframe=0
20:52:01.25   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 2) (CmdAI)
20:52:01.38   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 3) (CmdAI)
20:52:02.15   Warning: binding repeat_0(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
20:52:03.54   QueryMatches: Got [63] maps, [209] ids, [17] advertisements, startID [276531606]
20:52:03.54   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 23 matches
20:52:05.89   Warning: non auto-match upgrade not found in AE, tuning
20:52:08.80   QueryMatches: Got [63] maps, [209] ids, [17] advertisements, startID [276531881]
20:52:08.81   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 27 matches
20:52:13.72   QueryMatches: Got [64] maps, [206] ids, [17] advertisements, startID [276532111]
20:52:13.72   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 27 matches
20:52:17.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:52:17.00              PeerID 2 CONN ack=  0 (156ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.12%, pending=0, dead=0
20:52:17.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=166/244, voice=0/0, rchk=0/0, nudge=4/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
20:52:17.91   Activating screen: TacticalMap_widescreen
20:52:17.91   Activating screen: TextOverlayScreen
20:52:18.69   QueryMatches: Got [65] maps, [207] ids, [17] advertisements, startID [276532436]
20:52:18.69   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
20:52:22.00   Transport - median kBPS [hi/cur] sent = 2.3/0.4, recvd = 0.9/0.4, #p/sec[s/r] = 5.5/5.5, max unsent 0, version err 0, merge 7
20:52:23.67   QueryMatches: Got [64] maps, [206] ids, [17] advertisements, startID [276532603]
20:52:23.68   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 33 matches
20:52:23.69   We do not have a sync command for peer=2, currentframe=182
20:52:28.66   QueryMatches: Got [65] maps, [208] ids, [17] advertisements, startID [276532662]
20:52:28.66   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 38 matches
20:52:33.67   QueryMatches: Got [66] maps, [206] ids, [17] advertisements, startID [276532776]
20:52:33.68   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 39 matches
20:52:38.66   QueryMatches: Got [66] maps, [206] ids, [17] advertisements, startID [276532874]
20:52:38.66   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 33 matches
20:52:40.59   Activating screen: GameScreen
20:52:40.59   Activating screen: Decorators_widescreen
20:52:40.59   Activating screen: Taskbar_widescreen
20:52:40.59   Activating screen: SubtitleScreen
20:52:40.59   Activating screen: TextOverlayScreen
20:52:43.64   QueryMatches: Got [64] maps, [213] ids, [17] advertisements, startID [276532979]
20:52:43.65   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 25 matches
20:52:48.54   QueryMatches: Got [62] maps, [208] ids, [17] advertisements, startID [276533051]
20:52:48.55   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 12 matches
20:52:53.54   QueryMatches: Got [63] maps, [200] ids, [17] advertisements, startID [276533106]
20:53:08.37   Activating screen: TacticalMap_widescreen
20:53:08.37   Activating screen: TextOverlayScreen
20:53:15.58   Activating screen: GameScreen
20:53:15.58   Activating screen: Decorators_widescreen
20:53:15.58   Activating screen: Taskbar_widescreen
20:53:15.58   Activating screen: SubtitleScreen
20:53:15.58   Activating screen: TextOverlayScreen
20:53:18.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:53:18.00              PeerID 2 CONN ack=  0 (172ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=17ms) 99.72%, pending=0, dead=0
20:53:18.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=493/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
20:53:23.00   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.5, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 2
20:54:19.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:54:19.00              PeerID 2 CONN ack=  0 (131ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.83%, pending=0, dead=0
20:54:19.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=492/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
20:54:24.00   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 1
20:55:20.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:55:20.01              PeerID 2 CONN ack=  0 (138ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.88%, pending=0, dead=0
20:55:20.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=493/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
20:55:25.01   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.4, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 2
20:56:21.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:56:21.01              PeerID 2 CONN ack=  0 (141ms~0) unack=  2, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.91%, pending=0, dead=0
20:56:21.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=492/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
20:56:26.01   Transport - median kBPS [hi/cur] sent = 2.3/0.4, recvd = 0.9/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 0
20:57:12.79   We do not have a sync command for peer=2, currentframe=2503
20:57:12.92   We do not have a sync command for peer=2, currentframe=2503
20:57:22.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:57:22.00              PeerID 2 CONN ack=  0 (157ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.93%, pending=0, dead=0
20:57:22.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=489/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
20:57:27.00   Transport - median kBPS [hi/cur] sent = 2.3/0.6, recvd = 0.9/0.6, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 0
20:58:23.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:58:23.01              PeerID 2 CONN ack=  0 ( 45ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=16ms) 99.94%, pending=0, dead=0
20:58:23.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=494/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
20:58:28.01   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.6, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 0
20:59:24.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
20:59:24.01              PeerID 2 CONN ack=  0 (139ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=13ms) 99.95%, pending=0, dead=0
20:59: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=494/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
20:59:29.00   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 1
21:00:25.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:00:25.01              PeerID 2 CONN ack=  0 (146ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.95%, pending=0, dead=0
21:00:25.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=489/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:00:30.01   Transport - median kBPS [hi/cur] sent = 2.3/0.8, recvd = 0.9/0.4, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 1
21:00:51.37   Warning: binding repeat_1(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
21:01:26.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:01:26.00              PeerID 2 CONN ack=  0 (147ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=13ms) 99.96%, pending=0, dead=0
21:01:26.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=490/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:01:31.00   Transport - median kBPS [hi/cur] sent = 2.3/0.9, recvd = 0.9/0.6, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 1
21:01:47.96   Warning: binding repeat_1(Ability: abilities\facing_ability_ai) -- ui index '0' out of bounds; range is [1, 12]
21:02:27.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:02:27.01              PeerID 2 CONN ack=  0 (113ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.96%, pending=0, dead=0
21:02:27.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=491/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
21:02:32.00   Transport - median kBPS [hi/cur] sent = 2.3/1.2, recvd = 0.9/0.6, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 0
21:03:28.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:03:28.01              PeerID 2 CONN ack=  0 (144ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=11ms) 99.96%, pending=0, dead=0
21:03:28.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=491/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
21:03:33.00   Transport - median kBPS [hi/cur] sent = 2.3/1.2, recvd = 0.9/0.6, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 0
21:03:43.14   Activating screen: Command_Tree
21:03:45.48   Activating screen: Command_Branch
21:04:20.49   Activating screen: Command_Branch
21:04:29.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:04:29.01              PeerID 2 CONN ack=  0 ( 45ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.97%, pending=0, dead=0
21:04:29.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=491/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:04:34.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.6, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 5
21:05:01.34   Activating screen: Command_Branch
21:05:04.48   Activating screen: Command_Branch
21:05:05.74   Activating screen: NewObjective_widescreen
21:05:30.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:05:30.00              PeerID 2 CONN ack=  0 (145ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=18ms) 99.97%, pending=0, dead=0
21:05:30.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=489/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:05:35.01   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 2
21:06:03.22   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:06:03.22   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:06:31.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:06:31.01              PeerID 2 CONN ack=  0 (159ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.97%, pending=0, dead=0
21:06:31.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=491/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:06:36.00   Transport - median kBPS [hi/cur] sent = 2.3/1.2, recvd = 0.9/0.5, #p/sec[s/r] = 8.7/8.5, max unsent 0, version err 0, merge 11
21:07:32.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:07:32.01              PeerID 2 CONN ack=  0 (158ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=14ms) 99.97%, pending=0, dead=0
21:07:32.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=492/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:07:37.00   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.8, #p/sec[s/r] = 9.3/8.5, max unsent 0, version err 0, merge 14
21:07:39.24   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:07:58.70   Activating screen: Command_Branch
21:07:59.96   Activating screen: NewObjective_widescreen
21:08:32.05   Warning: binding repeat_2(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
21:08:32.05   Warning: binding repeat_4(Ability: abilities\smg_sten) -- ui index '0' out of bounds; range is [1, 12]
21:08:33.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:08:33.00              PeerID 2 CONN ack=  0 ( 48ms~0) unack=  2, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.98%, pending=0, dead=0
21:08:33.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=498/497, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:08:38.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.8/8.6, max unsent 0, version err 0, merge 22
21:09:34.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:09:34.00              PeerID 2 CONN ack=  0 (148ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=16ms) 99.98%, pending=0, dead=0
21:09:34.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=494/494, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:09:39.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.6, #p/sec[s/r] = 9.4/8.6, max unsent 0, version err 0, merge 14
21:10:25.19   Activating screen: Command_Branch
21:10:32.56   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:32.67   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:35.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:10:35.00              PeerID 2 CONN ack=  0 ( 76ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=16ms) 99.98%, pending=0, dead=0
21:10:35.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=494/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:10:37.89   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:37.89   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:37.89   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:37.89   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:37.89   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
21:10:37.89   Muting spew of bind updater.  Fix art and re-test.
21:10:40.01   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.7, #p/sec[s/r] = 8.9/8.5, max unsent 0, version err 0, merge 13
21:11:36.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:11:36.00              PeerID 2 CONN ack=  0 (172ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=14ms) 99.98%, pending=0, dead=0
21:11:36.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=494/494, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:11:41.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.7, #p/sec[s/r] = 8.9/8.5, max unsent 0, version err 0, merge 12
21:12:24.18   Activating screen: Command_Branch
21:12:25.51   Activating screen: NewObjective_widescreen
21:12:37.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:12:37.01              PeerID 2 CONN ack=  0 (117ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.98%, pending=0, dead=0
21:12:37.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=495/495, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:12:42.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.6, #p/sec[s/r] = 9.2/8.6, max unsent 0, version err 0, merge 11
21:12:44.28   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
21:13:38.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:13:38.00              PeerID 2 CONN ack=  0 ( 86ms~0) unack=  2, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.98%, pending=0, dead=0
21:13:38.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=492/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
21:13:43.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.6, #p/sec[s/r] = 9.4/8.5, max unsent 0, version err 0, merge 20
21:14:39.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:14:39.01              PeerID 2 CONN ack=  0 (132ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.98%, pending=0, dead=0
21:14:39.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=490/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
21:14:44.00   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.6, #p/sec[s/r] = 9.4/8.6, max unsent 0, version err 0, merge 18
21:14:53.13   Activating screen: Command_Branch
21:14:55.59   Activating screen: NewObjective_widescreen
21:15:40.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:15:40.01              PeerID 2 CONN ack=  0 ( 45ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.98%, pending=0, dead=0
21:15:40.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=494/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:15:45.01   Transport - median kBPS [hi/cur] sent = 2.3/1.2, recvd = 0.9/0.8, #p/sec[s/r] = 9.2/8.7, max unsent 0, version err 0, merge 15
21:16:41.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:16:41.01              PeerID 2 CONN ack=  0 ( 50ms~0) unack=  0, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=12ms) 99.98%, pending=0, dead=0
21:16:41.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=493/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:16:46.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.6, #p/sec[s/r] = 8.7/8.5, max unsent 0, version err 0, merge 10
21:17:42.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:17:42.01              PeerID 2 CONN ack=  0 ( 44ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.98%, pending=0, dead=0
21:17:42.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=492/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:17:46.12   Warning: binding repeat_3(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
21:17:46.12   Warning: binding repeat_5(Ability: abilities\victor_targeter) -- ui index '0' out of bounds; range is [1, 12]
21:17:47.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.7, #p/sec[s/r] = 9.2/8.5, max unsent 0, version err 0, merge 18
21:18:43.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:18:43.00              PeerID 2 CONN ack=  0 ( 56ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=11ms) 99.98%, pending=0, dead=0
21:18:43.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=492/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:18:48.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.6, #p/sec[s/r] = 9.5/8.5, max unsent 0, version err 0, merge 13
21:19:44.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:19:44.01              PeerID 2 CONN ack=  0 (121ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=15ms) 99.99%, pending=0, dead=0
21:19:44.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=494/494, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:19:49.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.5, #p/sec[s/r] = 8.7/8.5, max unsent 0, version err 0, merge 15
21:20:41.13   FXShader::SetVariable( "tex0", T ) uses unknown variable.
21:20:45.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:20:45.01              PeerID 2 CONN ack=  0 ( 70ms~0) unack=  2, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:20:45.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=493/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:20:50.01   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 9.1/8.6, max unsent 0, version err 0, merge 10
21:21:38.42   Activating screen: Command_Branch
21:21:40.46   Activating screen: NewObjective_widescreen
21:21:42.50   Activating screen: NewObjective_widescreen
21:21:46.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:21:46.00              PeerID 2 CONN ack=  0 (111ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=11ms) 99.99%, pending=0, dead=0
21:21:46.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=491/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:21:51.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.6, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 9
21:22:47.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:22:47.00              PeerID 2 CONN ack=  0 (146ms~0) unack=  0, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=13ms) 99.99%, pending=0, dead=0
21:22:47.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=499/496, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:22:52.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.8/8.5, max unsent 0, version err 0, merge 11
21:23:48.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:23:48.01              PeerID 2 CONN ack=  0 ( 74ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:23:48.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=491/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:23:53.00   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.5, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 2
21:24:49.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:24:49.00              PeerID 2 CONN ack=  0 ( 47ms~0) unack=  0, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:24:49.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=492/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
21:24:54.01   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.4, #p/sec[s/r] = 8.6/8.5, max unsent 0, version err 0, merge 5
21:25:50.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:25:50.00              PeerID 2 CONN ack=  0 (140ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:25:50.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=493/495, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
21:25:55.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 5
21:26:51.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:26:51.00              PeerID 2 CONN ack=  0 (147ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=8ms) 99.99%, pending=0, dead=0
21:26:51.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=494/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:26:56.00   Transport - median kBPS [hi/cur] sent = 2.3/1.1, recvd = 0.9/0.5, #p/sec[s/r] = 8.7/8.5, max unsent 0, version err 0, merge 11
21:27:52.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:27:52.00              PeerID 2 CONN ack=  0 (125ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=15ms) 99.99%, pending=0, dead=0
21:27:52.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=492/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:27:57.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.8/8.5, max unsent 0, version err 0, merge 6
21:28:53.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:28:53.00              PeerID 2 CONN ack=  0 (154ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=17ms) 99.99%, pending=0, dead=0
21:28:53.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=493/494, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:28:58.01   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.5, #p/sec[s/r] = 8.6/8.5, max unsent 0, version err 0, merge 11
21:29:28.37   Activating screen: TacticalMap_widescreen
21:29:28.37   Activating screen: TextOverlayScreen
21:29:30.50   Activating screen: GameScreen
21:29:30.50   Activating screen: Decorators_widescreen
21:29:30.50   Activating screen: Taskbar_widescreen
21:29:30.50   Activating screen: SubtitleScreen
21:29:30.50   Activating screen: TextOverlayScreen
21:29:54.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:29:54.01              PeerID 2 CONN ack=  0 (156ms~0) unack=  2, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:29:54.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=494/493, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:29:59.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.4, #p/sec[s/r] = 8.7/8.5, max unsent 0, version err 0, merge 19
21:30:55.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:30:55.00              PeerID 2 CONN ack=  0 (167ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=8ms) 99.99%, pending=0, dead=0
21:30:55.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=493/494, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:31:00.00   Transport - median kBPS [hi/cur] sent = 2.3/1.0, recvd = 0.9/0.4, #p/sec[s/r] = 9.1/8.6, max unsent 0, version err 0, merge 19
21:31:56.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:31:56.00              PeerID 2 CONN ack=  0 (109ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=10ms) 99.99%, pending=0, dead=0
21:31:56.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=497/497, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:31:57.14   We do not have a sync command for peer=2, currentframe=19326
21:31:57.26   We do not have a sync command for peer=2, currentframe=19326
21:32:00.46   We do not have a sync command for peer=2, currentframe=19351
21:32:01.01   Transport - median kBPS [hi/cur] sent = 2.3/0.9, recvd = 0.9/0.6, #p/sec[s/r] = 9.0/8.7, max unsent 0, version err 0, merge 24
21:32:57.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:32:57.00              PeerID 2 CONN ack=  0 (150ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=9ms) 99.99%, pending=0, dead=0
21:32:57.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=491/491, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:33:02.00   Transport - median kBPS [hi/cur] sent = 2.3/0.4, recvd = 0.9/0.4, #p/sec[s/r] = 8.5/8.7, max unsent 0, version err 0, merge 5
21:33:58.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:33:58.00              PeerID 2 CONN ack=  0 (131ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=14ms) 99.99%, pending=0, dead=0
21:33:58.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=491/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
21:34:03.01   Transport - median kBPS [hi/cur] sent = 2.3/0.5, recvd = 0.9/0.4, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 0
21:34:59.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:34:59.00              PeerID 2 CONN ack=  0 (163ms~0) unack=  1, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.20:6112; (ping=13ms) 99.99%, pending=0, dead=0
21:34:59.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=490/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
21:35:04.00   Transport - median kBPS [hi/cur] sent = 2.3/0.4, recvd = 0.9/0.4, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 1
21:35:20.07   Activating screen: TacticalMap_widescreen
21:35:20.07   Activating screen: TextOverlayScreen
21:35:25.01   Activating screen: GameScreen
21:35:25.01   Activating screen: Decorators_widescreen
21:35:25.01   Activating screen: Taskbar_widescreen
21:35:25.01   Activating screen: SubtitleScreen
21:35:25.01   Activating screen: TextOverlayScreen
21:35:32.27   MOD -- Player CPU - Hard (kill endgame) (frame 21055) (KillPlayer)
21:35:32.27   MOD -- Player CPU - Hard (kill endgame) (frame 21055) (KillPlayer)
21:35:32.27   MOD -- Game Over at frame 21055
21:35:32.27   MOD -- Could not find win condition 'annihilate'
21:35:32.27   MOD -- Could not find win condition 'annihilate'
21:35:32.27   MOD -- Could not find win condition 'annihilate'
21:35:32.28   GameApp::ReportStats
21:35:32.28   ReportMatchResults - onlyReportOnWin = 0
21:35:32.28   ReportMatchResults - SimID:1000, raceID:2, teamID:0, uid:0:3750302, result:5:PS_WON
21:35:32.28   ReportMatchResults - SimID:1001, raceID:5, teamID:0, uid:0:3748674, result:5:PS_WON
21:35:32.28   ReportMatchResults - SimID:1002, raceID:1, teamID:1, uid:0:0, AI player, ignoring
21:35:32.28   ReportMatchResults - SimID:1003, raceID:3, teamID:1, uid:0:0, AI player, ignoring
21:35:32.28   ReportMatchResults - reporting normal game results for match 0:276531799
21:35:32.28   ReportSimStats - storing simulation results for match 0:276531799
21:35:32.28   ReportStats - ReportSimStats failed
21:35:32.28   GameObj::DoGameOverPopup
21:35:32.28   Activating screen: GameOverDialog
21:35:32.28   ArbitrationMessageNeeded adding player 1000 [3750302] as victorious ally
21:35:32.28   ArbitrationMessageNeeded adding player 1001 [3748674] as victorious ally
21:35:32.28   ArbitrationMessageNeeded 2 allies vs 0 enemies result 0
21:35:32.28   GameOverDialog - normal game ending (no sync errors)
21:35:32.29   OnlineReportResults - player=3750302, gid = 276531799, time=2632, result=5, team=0, race=2, simPlayerID=1000
21:35:32.29   OnlineReportResults - player=3748674, gid = 276531799, time=2632, result=5, team=0, race=5, simPlayerID=1001
21:35:32.29   OnlineReportResults: Call started.
21:35:32.56   RendezvousNotifier - StatsUpdate MatchEvent - flushing player stats for pid = 3750302.
21:35:32.56   RNT_StatsUpdate: Win notification, userID [00000000:0039399e], matchType=14, race =2, level+=0
21:35:32.56   FEAppListener - Processing win/loss notification
21:35:32.56   RendezvousNotifier - received report simulation stats notification, sending simulation stats to lobby.
21:35:32.56   PostStoredStats: matchID = [00000000:107b8a57] is unknown
21:35:32.78   GetUserStats requested stats for PIDs ( 3750302 ) (best:0, full:1)
21:35:33.19   Activating screen: ViewStats
21:35:35.52   Activating screen: postgame_stats_skirm
21:35:40.74   Session::ProcessPeerMessages Session Host is marking PeerID 2 for death by dropout request
21:35:40.76   Session::GetEvent DestroyPeer success Peer 2 removed
21:35:40.76   Session::DestroyPeer - deallocating peer 2
21:35:40.76   Session - Requested Disconnect for connectionID 6
21:35:40.76   Deallocated route ID=7 for PeerID 2 at WINaddr:192.168.1.20:6112;
21:35:40.76   Session - Requested Disconnect for connectionID 7
21:35:40.76   Closed route 7<>5 for PeerID 2 at WINaddr:192.168.1.20:6112;
21:35:40.76   AdvertisementInternal::Process - EVENT_DESTROYPEER
21:35:40.76   P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [2].
21:35:40.76   OnDestroyedPeerEvent - calling RemovePeerFromSync.
21:35:40.76   P2PNetworkSynchronization::StartConsolidation - info, starting consolidation for station, station id= [2].
21:35:40.76   P2PNetworkSynchronization::Vote - info, voting for consolidation, station id = [2], frame = [21058]
21:35:40.76   P2PNetworkSynchronization::CheckConsolidation - info, consolidation for station [2] completed.
21:35:40.76   GameObjController - OnMatchEvent: event type 1
21:35:40.76   GameObj::OnRemoteStationDisconnected - info, remote station [2] was disconnected reason 1
21:35:40.76   GameObj::OnRemoteStationDisconnected - info, remote station [2] is disconnected
21:35:40.76   onplayerdrops 1001 1
21:35:40.76   GameObjController - OnMatchEvent: event type 4
21:35:40.79   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
21:35:40.98   OnlineUpdateAdvertisementAsync [(4:1) PythonCoreErrorCode::Exception]
21:36:00.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.10:6112; (ping=0ms) 100.00%, pending=0, dead=0
21:36:00.01   MessageCounts: inval=0/0, seek=0/4, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/1, data=268/269, voice=0/0, rchk=0/0, nudge=4/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
21:36:05.01   Transport - median kBPS [hi/cur] sent = 2.3/0.2, recvd = 0.9/0.2, #p/sec[s/r] = 4.4/4.4, max unsent 0, version err 0, merge 0
21:36:28.53   Activating screen: AppRestartingForm
21:36:28.53   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
21:36:28.53   Widget attempting to remove a non-existance listener. Widget:bkg_COH
21:36:28.53   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
21:36:28.53   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
21:36:28.53   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
21:36:28.53   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
21:36:28.54   APP -- Game Stop
21:36:28.54   GameApp::TransitionGameStop
21:36:28.54   GameApp::TransitionQuitCommon
21:36:28.54   PerformanceRecorder: Updating profile history
21:36:28.54   GetMaxFrameTimeFromProfile: players=4 expected FPS=9.997039, bars=1, max avg=0.073, sd=0.027, 5 samples =  0.06 0.05 0.10 0.11 0.05
21:36:28.54   PerformanceRecorder::EndRecording - game size=4, max average=0.061324, worst frame=0.100030
21:36:28.54    Recording: No [4 players]
21:36:28.54    Total render, sim time: 4.54, 5.00 sec
21:36:28.54    Total render, sim frames: 300, 0
21:36:28.54    Max/Avg: 0.06, 0.03 sec (fps=16.31, 30.51) (131 samples)
21:36:28.54    Bars: 5
21:36:28.54   Profile Max Performance[4 players] =  0.06 0.05 0.10 0.11 0.05
21:36:28.54   Profile Avg Performance[4 players] =  0.03 0.03 0.04 0.05 0.03
21:36:28.54   GameObj::ShutdownGameObj
21:36:28.54   Calling disconnect...
21:36:28.54   Session is marking the local peer PeerID 1 for death
21:36:28.54   OnlineLeaveAdvertisementAsync: Match id = 276531799 started, nothing to do
21:36:28.54   Session::GetEvent DestroyPeer success Peer 1 removed
21:36:28.54   Session::DestroyPeer - deallocating peer 1
21:36:28.54   Session - Requested Disconnect for connectionID 4
21:36:28.54   Deallocated route ID=5 for PeerID 1 at WINaddr:192.168.1.10:6112;
21:36:28.54   Session - Requested Disconnect for connectionID 5
21:36:28.54   Closed route 5<>5 for PeerID 1 at WINaddr:192.168.1.10:6112;
21:36:28.54   AdvertisementInternal::Process - EVENT_DESTROYPEER
21:36:28.54   P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [1].
21:36:28.54   OnDestroyedPeerEvent - calling RemovePeerFromSync.
21:36:28.54   P2PNetworkSynchronization::StartConsolidation - info, starting consolidation for station, station id= [1].
21:36:28.54   P2PNetworkSynchronization::StartConsolidation - peer rec state not connected, station id= [2].
21:36:28.54   P2PNetworkSynchronization::Vote - info, voting for consolidation, station id = [1], frame = [21058]
21:36:28.54   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
21:36:28.54   Consolidation - cannot find station, id= [ffffffffffffffff] in vote map.
21:36:28.54   Consolidation - no voter, consolidation done
21:36:28.54   P2PNetworkSynchronization::CheckConsolidation - info, consolidation for station [1] completed.
21:36:28.54   GameObjController - OnMatchEvent: event type 1
21:36:28.54   GAME -- Frame 21056 - SimKillPlayer - Destroying SimPlayer 1000, reason 0
21:36:28.54   MOD -- Player Brenky88 (disconnected from network) (frame 21056) (KillPlayer)
21:36:28.54   MOD -- Could not find win condition 'annihilate'
21:36:28.54   MOD -- Could not find win condition 'annihilate'
21:36:28.54   MOD -- Could not find win condition 'annihilate'
21:36:28.54   GameApp::ReportStats
21:36:28.54   ReportStats - already reported for match 0:276531799
21:36:28.54   LeaveMatchInternal - no action taken, needLeave=0, service=0
21:36:28.54   Destroyed Matchinfo
21:36:28.54   GAME -- Ending mission (Connection Lost)
21:36:28.58   GameObjLoader 06fef080 - Unload
21:36:28.58   GameObjLoader 06fef080 - resetting counters
21:36:28.62   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
21:36:28.77   GameObjLoader 06feef20 - Unload
21:36:28.77   GameObjLoader 06feef20 - resetting counters
21:36:28.83   SOUND -- Shutting down ...
21:36:28.91   SOUND -- Shutdown completed!
21:36:28.91   MessageInternal::DestroyChannel: Destroyed channel 474d4343
21:36:28.91   MessageInternal::DestroyChannel: Destroyed channel 53594e43
21:36:28.91   GAME -- Beginning FE
21:36:28.91   Sent message game CompanyOfHeroes allowtraffic
21:36:28.91   UIFrontEnd - Loading Front End
21:36:28.91   THREAD: Hyper-Threading Technology Processors are not detected.
21:36:29.00   SOUND -- Initializing ...
21:36:29.04   SOUND -- Initialization completed!
21:36:29.04   UIFrontEnd - Initializing Forms
21:36:30.47   CampaignFilter::BindFilterSpecificWidgets()
21:36:30.51   Turning off auto-selection of profiles, we already have a profile selected.
21:36:30.52   MOD -- Mounting Eastern_Front_DLC1, 1.0
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC1\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC1\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC1\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC1\Movies\'
21:36:30.52   MOD -- Mounting Eastern_Front_DLC2, 1.0
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC2\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC2\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC2\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC2\Movies\'
21:36:30.52   MOD -- Mounting Eastern_Front_DLC3, 1.0
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC3\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC3\DataArtHigh\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC3\DataSoundEnglish\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\DLC3\Movies\'
21:36:30.52   MOD -- Mounting Eastern_Front_OPS, 1.0
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\Ops\Data\'
21:36:30.52   MOD -- Mounting Eastern_Front, 1.0
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\'
21:36:30.52   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\RelicOnline\Data\'
21:36:30.53   FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\'
21:36:30.53   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\Engine\Data\'
21:36:30.53   FILESYSTEM -- filepath failure, path does not exist 'WW2\Data\'
21:36:30.53   FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\'
21:36:30.53   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\Data\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\Data\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtHigh\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\DataArtHigh\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\DataSoundHigh\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\DataSound\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\'
21:36:30.54   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Engine\Locale\English\Data\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\DataSoundEnglish\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\CoH\Locale\English\Data\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\DataAttrib\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Eastern_Front\Movies\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\Engine\Movies\'
21:36:30.57   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\Brenky\Documents\My Games\Company of Heroes\WW2\Movies\'
21:36:30.58   Activating screen: AppRestartingForm
21:36:30.58   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
21:36:30.58   OnlineLeaveAdvertisementAsync: Match id = 276531799 started, nothing to do
21:36:30.58   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
21:36:30.88   PeerRemoveAll - flushing local session peer data
21:36:30.88   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
21:36:31.14   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
21:36:31.15   CRC & Version Info : 0000025a:69b4e393:ec3c9e0d eastern_front:602:factionfix.dll 1
21:36:31.15   Activating screen: FEMovie
21:36:31.15   Activating screen: OnlineWidget
21:36:31.15   Activating screen: FE_mm_01
21:36:31.19   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
21:36:31.19   GAME -- Setting campaign state to 'dlc1'
21:36:31.19   GAME -- Closing state 'dlc1'
21:36:31.20   GAME -- Setting campaign state to 'dlc2'
21:36:31.20   GAME -- Closing state 'dlc2'
21:36:31.20   GAME -- Setting campaign state to 'dlc3'
21:36:31.20   GAME -- Closing state 'dlc3'
21:37:06.01   Transport - median kBPS [hi/cur] sent = 2.3/0.0, recvd = 0.9/0.0, #p/sec[s/r] = 0.5/0.8, max unsent 0, version err 0, merge 0
21:37:43.18   SPDx9 -- 718MB available Texture Memory
21:37:49.86   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
21:37:49.86   Activating screen: OnlineSelectGame
21:37:49.95   FILESYSTEM -- filepath failure, missing alias '.sgb'
21:37:50.02   Activating screen: RelicOnlineChat
21:37:50.02   Activating screen: RelicOnlineNewsScreen
21:37:50.07   Activating screen: RelicOnlineStatsScreen
21:37:50.14   Activating screen: Achievements
21:37:50.14   GAME -- Setting campaign state to 'dlc1'
21:37:50.14   GAME -- Closing state 'dlc1'
21:37:50.14   GAME -- Setting campaign state to 'dlc2'
21:37:50.14   GAME -- Closing state 'dlc2'
21:37:50.14   GAME -- Setting campaign state to 'dlc3'
21:37:50.14   GAME -- Closing state 'dlc3'
21:37:50.14   GAME -- Setting campaign state to 'dlc1'
21:37:50.14   GAME -- Closing state 'dlc1'
21:37:50.15   Activating screen: GameHistory
21:37:50.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:37:50.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:37:50.15   GAME -- Error reading header for recorded game PLAYBACK:temp.rec
21:37:50.15   Activating screen: OnlineSelectGame
21:37:50.15   Activating screen: RelicOnlineTabs
21:37:50.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:37:50.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:37:50.21   GAME -- Error reading header for recorded game PLAYBACK:temp.rec
21:37:50.28   JoinChannelAsyncJob: Joining channel 21
21:37:51.09   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 801
21:37:51.09   Sent message game CompanyOfHeroes softthrottle
21:37:51.10   Got dlman msg [ack game CompanyOfHeroes softthrottle]
21:37:51.12   QueryMatches: Got [50] maps, [170] ids, [17] advertisements, startID [276521326]
21:37:51.14   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 151 matches
21:37:55.80   QueryMatches: Got [49] maps, [164] ids, [17] advertisements, startID [276539132]
21:37:55.80   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 129 matches
21:37:56.17   GetPingAsync: Reporting Ping results for 5 players (success=2, fails=3)
21:38:00.79   QueryMatches: Got [48] maps, [164] ids, [17] advertisements, startID [276540304]
21:38:00.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 112 matches
21:38:02.16   GetPingAsync: Reporting Ping results for 5 players (success=1, fails=4)
21:38:05.79   QueryMatches: Got [49] maps, [166] ids, [17] advertisements, startID [276540846]
21:38:05.79   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 97 matches
21:38:07.00   Transport - median kBPS [hi/cur] sent = 2.3/1.7, recvd = 0.9/0.1, #p/sec[s/r] = 4.8/1.2, max unsent 0, version err 0, merge 0
21:38:08.16   GetPingAsync: Reporting Ping results for 8 players (success=4, fails=4)
21:38:10.77   QueryMatches: Got [50] maps, [164] ids, [17] advertisements, startID [276541036]
21:38:10.77   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 80 matches
21:38:14.17   GetPingAsync: Reporting Ping results for 6 players (success=3, fails=3)
21:38:15.63   QueryMatches: Got [51] maps, [163] ids, [17] advertisements, startID [276541205]
21:38:15.63   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 65 matches
21:38:20.15   GetPingAsync: Reporting Ping results for 5 players (success=1, fails=4)
21:38:20.64   QueryMatches: Got [49] maps, [166] ids, [17] advertisements, startID [276541375]
21:38:20.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 52 matches
21:38:25.48   QueryMatches: Got [52] maps, [164] ids, [17] advertisements, startID [276541447]
21:38:25.49   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 38 matches
21:38:26.16   GetPingAsync: Reporting Ping results for 6 players (success=1, fails=5)
21:38:30.62   QueryMatches: Got [49] maps, [169] ids, [17] advertisements, startID [276541527]
21:38:30.62   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 28 matches
21:38:32.17   GetPingAsync: Reporting Ping results for 9 players (success=1, fails=8)
21:38:35.48   QueryMatches: Got [53] maps, [171] ids, [17] advertisements, startID [276541611]
21:38:35.48   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches
21:38:38.17   GetPingAsync: Reporting Ping results for 5 players (success=0, fails=5)
21:38:40.48   QueryMatches: Got [49] maps, [169] ids, [17] advertisements, startID [276541696]
21:38:40.49   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
21:38:44.17   GetPingAsync: Reporting Ping results for 7 players (success=2, fails=5)
21:38:45.48   QueryMatches: Got [50] maps, [168] ids, [17] advertisements, startID [276541737]
21:38:45.49   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 2 matches
21:38:50.17   GetPingAsync: Reporting Ping results for 6 players (success=5, fails=1)
21:38:50.77   QueryMatches: Got [49] maps, [163] ids, [17] advertisements, startID [276537064]
21:38:50.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
21:38:55.78   QueryMatches: Got [52] maps, [166] ids, [17] advertisements, startID [276540280]
21:38:55.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 12 matches
21:38:56.17   GetPingAsync: Reporting Ping results for 5 players (success=0, fails=5)
21:39:00.76   QueryMatches: Got [54] maps, [165] ids, [17] advertisements, startID [276540905]
21:39:00.76   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 16 matches
21:39:02.18   GetPingAsync: Reporting Ping results for 6 players (success=1, fails=5)
21:39:05.64   QueryMatches: Got [58] maps, [167] ids, [17] advertisements, startID [276541136]
21:39:05.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 20 matches
21:39:08.01   Transport - median kBPS [hi/cur] sent = 2.7/2.2, recvd = 0.9/0.0, #p/sec[s/r] = 19.0/1.5, max unsent 0, version err 0, merge 0
21:39:08.34   SPDx9 -- 718MB available Texture Memory
21:39:08.36   GetPingAsync: Reporting Ping results for 8 players (success=2, fails=6)
21:39:09.18   CancelAutoMatching - called
21:39:09.18   Activating screen: OnlineSelectGame
21:39:09.18   Activating screen: RelicOnlineTabs
21:39:09.18   Activating screen: FEMovie
21:39:09.18   Activating screen: OnlineWidget
21:39:09.18   Activating screen: FE_mm_01
21:39:09.18   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:39:09.18   GameInfo::ResetInfo - SyncLevel set to 0 on reset
21:39:09.18   GAME -- Error reading header for recorded game PLAYBACK:temp.rec
21:39:09.18   GAME -- Setting campaign state to 'dlc1'
21:39:09.18   GAME -- Closing state 'dlc1'
21:39:09.18   GAME -- Setting campaign state to 'dlc2'
21:39:09.18   GAME -- Closing state 'dlc2'
21:39:09.19   GAME -- Setting campaign state to 'dlc3'
21:39:09.19   GAME -- Closing state 'dlc3'
21:39:09.81   Activating screen: QuitPromptDialog
21:39:10.44   GameApp::Quit( exitValue = 0 )
21:39:10.46   GameApp::ShutFrontEnd
21:39:10.47   GAME -- Ending FE
21:39:10.47   UIFrontEnd - Unloading Front End
21:39:10.49   SOUND -- Shutting down ...
21:39:10.53   SOUND -- Shutdown completed!
21:39:10.58   ~FEAppListener - Flushing queued online notifications
21:39:10.81   DLLDriverLinker -- 2 DLL drivers found.
21:39:10.81   ActionDatabase : [2572] global actions, [115] dummy actions.
21:39:10.81   NetworkManager::Destroy - destroying network manager
21:39:10.81   RendezvousManager::Shutdown - deleting instance
21:39:10.81   RendezvousManager - terminating all server calls in progress
21:39:10.81   CallManager - terminating all server calls in progress (4 in progress)
21:39:10.81   RendezvousManager - destroying chat handler
21:39:10.81   RendezvousManager::DestroyNATTraversalClient - NAT traversal no longer available.
21:39:10.81   ~RendezvousManager - disconnecting from server
21:39:10.99   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
21:39:10.99   MessageInternal::DestroyChannel: Destroyed channel 50525459
21:39:10.99   PartyInternal: Fade to black
21:39:10.99   AutomatchInternal: Fade to black
21:39:10.99   PeerRemoveAll - flushing local session peer data
21:39:10.99   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
21:39:10.99   MessageInternal::DestroyChannel: Destroyed channel 47535450
21:39:10.99   Session - Requested Disconnect for connectionID 1
21:39:10.99   SessionManager::UnregisterSession - Unregistering session 07b70448
21:39:10.99   SessionManager::~SessionManager - uninitializing
21:39:11.00   Net::ThreadFunction - Returning from the network thread function...
21:39:11.01   Transport::~Transport - shutting down transport
21:39:11.01   Transport::~Connection - closing WINaddr:255.255.255.255:6112;,WINaddr:255.255.255.255:6112;
21:39:11.01   Transport::~Connection - closing WINaddr:192.168.1.10:6112;,WINaddr:192.168.1.10:6112;
21:39:11.01   Transport::~Connection - closing WINaddr:192.168.1.20:6112;,WINaddr:192.168.1.20:6112;
21:39:11.01   AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job GetPingAsync is still running
21:39:11.01   AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job GetAdvertisementsAsync is still running
21:39:11.01   AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job GetRelationshipAsync is still running
21:39:11.02   AsyncJobDriver::~AsyncJobDriver() - dynamically allocated job LeaveChannelAsyncJob is still running
21:39:11.02   Wrote 971 bytes to network datastore login_cache.bin
21:39:11.02   Wrote 135912 bytes to network datastore global_cache.bin
21:39:11.03   Wrote 243550 bytes to network datastore ping_cache.bin
21:39:11.04   Datastore -- uninitialize complete
21:39:11.04   QuazalInitializer - static uninit of Quazal library
21:39:11.04   QuazalInitializer - unhooking memory 0 currently allocated, 625837 allocations, total of 107895452 bytes
