-- Log file for all dbTracef messages --

16:50:21.12   NetworkManager::Create - creating network manager
16:50:21.12   Read 599 bytes from network datastore login_cache.bin
16:50:21.12   Read 132668 bytes from network datastore global_cache.bin
16:50:21.12   Read 97282 bytes from network datastore ping_cache.bin
16:50:21.12   QuazalInitializer - static initializing Quazal library
16:50:21.12   PingCache - populating cache with 2417 pings
16:50:21.13   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
16:50:21.13   WinTransport - CreateSocket exclusive broadcast socket was available.
16:50:21.13   WinTransport - CreateSocket listening for broadcasts on default port
16:50:21.13   WinTransport - Host Name: James-PC, aliases: , type=AF_INET, len=4
16:50:21.13   WinTransport - Host IP Address #0: 192.168.0.2
16:50:21.13   WinTransport - Interface #0: ip:192.168.0.2, broadcast:192.168.0.2, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
16:50:21.13   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
16:50:21.13   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
16:50:21.13   WinTransport - Quazal address string = udp:/address=192.168.0.2;port=6112
16:50:21.13   SessionManager - Peer Header Size = 16 bytes
16:50:21.13   SessionManager - Game Data overhead = 7 bytes
16:50:21.13   SessionManager - Proxy overhead = 7 bytes
16:50:21.13   MessageInternal::CreateChannel: Created channel 47535450
16:50:21.13   Session::Initialize - info, initializing session object, using threads.
16:50:21.13   SessionManager::RegisterSession - Registering new session 03b2f740
16:50:21.13   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
16:50:21.13   AutomatchInternal: Instantiating
16:50:21.13   PartyInternal: Instantiating
16:50:21.13   MessageInternal::CreateChannel: Created channel 50525459
16:50:21.13   MessageInternal::CreateChannel: Created channel 51434b4d
16:50:21.13   Net::ThreadFunction - Entering network thread function...
16:50:21.13   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
16:50:21.13   MessageInternal::CreateChannel: Created channel 534d5347
16:50:21.13   MessageInternal::CreateChannel: Created channel 474d4343
16:50:21.13   MessageInternal::CreateChannel: Created channel 474f424a
16:50:21.13   MessageInternal::CreateChannel: Created channel 4d4f444d
16:50:21.13   MessageInternal::CreateChannel: Created channel 53594e43
16:50:21.13   MessageInternal::DestroyChannel: Destroyed channel 534d5347
16:50:21.13   MessageInternal::DestroyChannel: Destroyed channel 474d4343
16:50:21.13   MessageInternal::DestroyChannel: Destroyed channel 474f424a
16:50:21.13   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
16:50:21.13   MessageInternal::DestroyChannel: Destroyed channel 53594e43
16:50:21.13   GAME -- Available memory: 8174MB Physical RAM, 8172MB Pagefile, 4095 Virtual Address Space
16:50:21.18   Transport - Largest received is now 19
16:50:21.18   Transport::OpenInternal request to WINaddr:192.168.0.2:6112;
16:50:21.92   DLLDriverLinker -- Adding driver 'spDx10.dll'.
16:50:21.92   DLLDriverLinker -- Adding driver 'spDx9.dll'.
16:50:21.92   DLLDriverLinker -- 2 DLL drivers found.
16:50:21.96   SPDx10 -- Adapter [NVIDIA GeForce GTX 560 Ti  ]: 978MB dedicated video memory, 0MB dedicated system memory and 3071MB shared system memory.
16:50:23.18   DLLDriverLinker -- 2 DLL drivers found.
16:50:23.23   SPOOGE - Driver[DirectX10 Rendering Device] version[4,36]
16:50:23.23   GAME -- Resolution set to 1920x1200 (fullscreen).
16:50:23.24   SPDx10 -- Adapter Description = NVIDIA GeForce GTX 560 Ti
16:50:23.24   SPDx10 -- Driver Vendor = 0x000010de  Device = 0x00001200  SubSys = 0x23821462  Rev = 0x000000a1
16:50:23.24   SPDx10 -- Driver Version  Product = 0x0009  Version = 0x0012  SubVersion = 0x00  Build = 310.33
16:50:23.24   SPDx10 -- Driver LUID = 0x00000000-0x00009612
16:50:23.24   SPDx10 -- 978MB dedicated video memory, 0MB dedicated system memory and 3071MB shared system memory available.
16:50:23.25   ShaderDatabase: using shader profile [ps40]
16:50:23.89   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:50:23.98   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:50:23.98   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
16:50:24.29   GameObjLoader 10a32950 - resetting counters
16:50:24.29   GameObjLoader 10a32950 - Created loader
16:50:24.29   GameObjLoader 10a32ab0 - resetting counters
16:50:24.29   GameObjLoader 10a32ab0 - Created loader
16:50:24.45   GAME -- Beginning FE
16:50:24.45   Sent message game CompanyOfHeroes started 4572 602 allowtraffic
16:50:24.45   RemoteDLManager - Connection Restored.
16:50:24.45   UIFrontEnd - Loading Front End
16:50:24.45   THREAD: Hyper-Threading Technology Processors are not detected.
16:50:24.49   SOUND -- Initializing ...
16:50:24.52   INNIMapDCA Key not found: sp_speechducker::time
16:50:24.73   SOUND -- Initialization completed!
16:50:24.73   UIFrontEnd - Initializing Forms
16:50:25.52   CampaignFilter::BindFilterSpecificWidgets()
16:50:25.54   Activating screen: AppLoadingForm
16:50:25.54   SetupProductLoadingArt - choosing bgArt = 1 (gold=0)
16:50:25.54   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
16:50:25.98   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
16:50:25.98   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
16:50:25.98   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
16:50:25.98   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
16:50:25.98   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
16:50:25.98   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
16:50:26.14   GAME -- Using player profile JAMES-PC
16:50:26.61   Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
16:50:26.74   Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
16:50:28.24   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
16:50:28.24   RendezvousManager: CreateSession - starting profile=Guest login
16:50:30.50   RendezvousManager: Login complete and successfull
16:50:30.52   RendezvousManager initialized
16:50:30.79   Current server English:live version is 602.0, client is 602.0
16:50:30.80   OnConnect: successful connection established, enabling reconnect
16:50:30.80   OnConnect: this wasnt a reconnect, no need for autologin
16:50:30.82   Logging in diegobah on controller:0
16:50:31.44   Login completed: ACCOUNT_VALIDATED
16:50:31.44   Found 1 profiles for account diegobah
16:50:31.44   Found profile: diegobah
16:50:31.44   installed_products = ( COH CXP1 DLC1 DLC2 DLC3 )
16:50:31.44   OnLogin: no previous login, auto selecting profile not required
16:50:31.47   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
16:50:31.47   CRC & Version Info : 0000025a:c1dd11bc:ec3c9e0d eastern_front:602:factionfix.dll 1
16:50:31.47   Activating screen: FEMovie
16:50:31.47   Activating screen: OnlineWidget
16:50:31.47   Activating screen: RelicOnlineProfileSelect
16:50:31.48   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
16:50:31.48   Activating screen: RelicOnlineWait
16:50:31.48   RendezvousManager - destroying chat handler
16:50:31.48   RendezvousManager: CreateSession - starting logout profile = 100:Guest
16:50:31.79   RendezvousManager: Logout complete
16:50:31.79   RendezvousManager - terminating all server calls in progress
16:50:31.79   CallManager - terminating all server calls in progress (1 in progress)
16:50:31.79   RendezvousManager: OnCredentialsEvent - starting profile login
16:50:33.87   RendezvousManager: Login complete and successfull
16:50:33.87   RendezvousManager - creating chat handler
16:50:33.88   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
16:50:34.21   QuazalSelectProfileAsync - Got UserID
16:50:34.23   Transport - Largest sent is now 16
16:50:34.47   Transport - Largest received is now 41
16:50:34.49   SelectProfileAsync - RegisterLocalURLs public [udp:/address=101.165.4.59;port=6112;PID=4750714;RVCID=65579273], private [udp:/address=192.168.0.2;port=6112;PID=4750714]
16:50:34.49   GetUserStats requested stats for PIDs ( 4750714 ) (best:0, full:1)
16:50:34.76   QuazalSelectProfileAsync - Got Full Stats
16:50:35.04   GetAutomatchMaps: Got [35] maps
16:50:35.05   PopulateArmyListBox - skipping race 2
16:50:35.05   PopulateArmyListBox - skipping race 0
16:50:35.05   PopulateArmyListBox - skipping race 1
16:50:35.05   PopulateArmyListBox - skipping race 3
16:50:35.05   AutoMatchForm::OnArmySelectionChanged - sending request info
16:50:35.05   GetMaxFrameTimeFromProfile: players=2 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples =
16:50:35.05   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
16:50:35.06   QuazalSelectProfileAsync - Got Automatch maps
16:50:37.34   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
16:50:37.62   Profile [00000000:00487d7a] selected on controller#0
16:50:37.62   Activating screen: FEMovie
16:50:37.62   Activating screen: OnlineWidget
16:50:37.62   Activating screen: FE_mm_01
16:50:37.62   Activating screen: RelicOnlineWait
16:50:37.64   GAME -- Setting campaign state to 'coh'
16:50:37.64   GAME -- Closing state 'coh'
16:50:37.65   GAME -- Setting campaign state to 'cxp2'
16:50:37.65   GAME -- Closing state 'cxp2'
16:50:37.65   GAME -- Setting campaign state to 'cxp1'
16:50:37.65   GAME -- Closing state 'cxp1'
16:50:37.65   GAME -- Setting campaign state to 'dlc1'
16:50:37.65   GAME -- Closing state 'dlc1'
16:50:37.65   GAME -- Setting campaign state to 'dlc2'
16:50:37.65   GAME -- Closing state 'dlc2'
16:50:37.65   GAME -- Setting campaign state to 'dlc3'
16:50:37.65   GAME -- Closing state 'dlc3'
16:50:40.05   Activating screen: MessageBoxPopup
16:50:40.05   Created Matchinfo
16:50:40.05   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
16:50:40.05   starting online hosting
16:50:40.08   OnlineHostAsync: initiating CallCreateMatch
16:50:40.34   OnlineHostAsync: created gid=267983713
16:50:40.35   Transport - Largest sent is now 78
16:50:40.44   RendezvousNotifier - Received Participate ParticipationEvent.
16:50:40.68   Transport - Largest received is now 103
16:50:40.86   OnlineHostAsync - RegisterLocalURLs public [udp:/address=101.165.4.59;port=6112;PID=4750714;RVCID=65579273], private [udp:/address=192.168.0.2;port=6112;PID=4750714]
16:50:41.13   OnlineHostAsync: initiating UpdateSessionURL [gid=267983713, url=udp:/address=101.165.4.59;port=6112;PID=4750714;RVCID=65579273]
16:50:41.43   OnJoinAdvertisementSuccess - joined online match, server leave notification required
16:50:41.43   starting local hosting
16:50:41.43   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
16:50:41.43   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.0.2:6112;
16:50:41.43   Transport::OpenInternal request to WINaddr:192.168.0.2:6112;
16:50:41.43   Session::Host sid = FF91B61, hostURL = , local addresses = WINaddr:192.168.0.2:6112;
16:50:41.43   ValidateCustomData: called with 397 bytes of custom data
16:50:41.43   Host accepted Peer 1 into the match at address list=WINaddr:192.168.0.2:6112;, routes=WINaddr:192.168.0.2:6112;
16:50:41.43   AdvertisementInternal::Process - EVENT_NEWPEER
16:50:41.43   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
16:50:41.44   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
16:50:41.44   hosting - Session is connected
16:50:41.44   Net::Session::SetVisible - session is set to INVISIBLE.
16:50:41.46   hosting completed successfully
16:50:41.46   HostAsync - completed with HostResult = 0
16:50:41.46   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
16:50:41.46   Activating screen: OnlineGameSetup
16:50:41.46   MessageInternal::CreateChannel: Created channel 534d5347
16:50:41.46   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:50:41.46   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
16:50:41.46   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
16:50:41.46   MatchInternal::SetMatchType - new type 14 - updating server
16:50:41.47   SetVisible called while !IsConnected
16:50:41.48   GetMaxFrameTimeFromProfile: players=4 expected FPS=52.305599, bars=5, max avg=0.018, sd=0.001, 5 samples =  0.02 0.02 0.02 0.02 0.02
16:50:41.50   Activating screen: RelicOnlineChat
16:50:41.50   Activating screen: RelicOnlineNewsScreen
16:50:41.53   Activating screen: RelicOnlineStatsScreen
16:50:41.53   Activating screen: Achievements
16:50:41.53   GAME -- Setting campaign state to 'coh'
16:50:41.53   GAME -- Closing state 'coh'
16:50:41.53   GAME -- Setting campaign state to 'cxp2'
16:50:41.53   GAME -- Closing state 'cxp2'
16:50:41.53   GAME -- Setting campaign state to 'cxp1'
16:50:41.53   GAME -- Closing state 'cxp1'
16:50:41.54   GAME -- Setting campaign state to 'dlc1'
16:50:41.54   GAME -- Closing state 'dlc1'
16:50:41.54   GAME -- Setting campaign state to 'dlc2'
16:50:41.54   GAME -- Closing state 'dlc2'
16:50:41.54   GAME -- Setting campaign state to 'dlc3'
16:50:41.54   GAME -- Closing state 'dlc3'
16:50:41.54   GAME -- Setting campaign state to 'dlc1'
16:50:41.54   GAME -- Closing state 'dlc1'
16:50:41.54   Activating screen: GameHistory
16:50:41.54   GAME -- Setting campaign state to 'coh'
16:50:41.55   GAME -- Closing state 'coh'
16:50:41.55   GAME -- Setting campaign state to 'cxp2'
16:50:41.55   GAME -- Closing state 'cxp2'
16:50:41.55   GAME -- Setting campaign state to 'cxp1'
16:50:41.55   GAME -- Closing state 'cxp1'
16:50:41.55   GAME -- Setting campaign state to 'dlc1'
16:50:41.55   GAME -- Closing state 'dlc1'
16:50:41.55   GAME -- Setting campaign state to 'dlc2'
16:50:41.55   GAME -- Closing state 'dlc2'
16:50:41.55   GAME -- Setting campaign state to 'dlc3'
16:50:41.55   GAME -- Closing state 'dlc3'
16:50:41.55   Activating screen: OnlineGameSetup
16:50:41.55   Activating screen: RelicOnlineTabs
16:50:41.55   AutomatchInternal::OnHostComplete - Completed Host with success=1
16:50:41.55   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
16:50:41.55   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
16:50:41.55   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
16:50:41.55   Net::Session::SetVisible - session is set to INVISIBLE.
16:50:41.55   GetMaxFrameTimeFromProfile: players=4 expected FPS=52.305599, bars=5, max avg=0.018, sd=0.001, 5 samples =  0.02 0.02 0.02 0.02 0.02
16:50:41.60   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:50:41.61   GAME -- Setting campaign state to 'coh'
16:50:41.61   GAME -- Closing state 'coh'
16:50:41.61   GAME -- Setting campaign state to 'cxp2'
16:50:41.61   GAME -- Closing state 'cxp2'
16:50:41.61   GAME -- Setting campaign state to 'cxp1'
16:50:41.61   GAME -- Closing state 'cxp1'
16:50:41.61   GAME -- Setting campaign state to 'dlc1'
16:50:41.61   GAME -- Closing state 'dlc1'
16:50:41.62   GAME -- Setting campaign state to 'dlc2'
16:50:41.62   GAME -- Closing state 'dlc2'
16:50:41.62   GAME -- Setting campaign state to 'dlc3'
16:50:41.62   GAME -- Closing state 'dlc3'
16:50:41.62   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
16:50:41.64   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:50:42.43   Requesting Relic Downloader soft throttle call [GetNewsAsync] has time 814
16:50:42.43   Sent message game CompanyOfHeroes softthrottle
16:50:42.45   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:50:43.63   Requesting Relic Downloader hard throttle call [GetNewsAsync] has time 2014
16:50:43.63   Sent message game CompanyOfHeroes hardthrottle
16:50:43.65   Got dlman msg [ack game CompanyOfHeroes hardthrottle]
16:50:44.97   GetUserStats requested stats for PIDs ( 247651 404727 943846 1235041 2799203 ) (best:5, full:0)
16:50:44.97   QueryMatches: Got [31] maps, [55] ids, [17] advertisements, startID [1]
16:50:44.97   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 38 matches
16:50:46.82   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 804
16:50:46.82   Sent message game CompanyOfHeroes softthrottle
16:50:46.84   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:50:47.07   QueryMatches: Got [31] maps, [54] ids, [17] advertisements, startID [267983240]
16:50:47.07   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 21 matches
16:50:51.20   Activating screen: GameHistory
16:50:51.20   Activating screen: RelicOnlineTabs
16:50:51.82   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 801
16:50:51.82   Sent message game CompanyOfHeroes softthrottle
16:50:51.84   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:50:52.06   QueryMatches: Got [33] maps, [54] ids, [17] advertisements, startID [267983587]
16:50:52.06   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 6 matches
16:50:56.81   QueryMatches: Got [31] maps, [55] ids, [17] advertisements, startID [267983707]
16:50:58.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:50:58.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:50:58.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:51:06.38   Transport - Largest sent is now 79
16:51:22.01   Transport - median kBPS [hi/cur] sent = 0.2/0.0, recvd = 0.2/0.0, #p/sec[s/r] = 0.3/0.3, max unsent 0, version err 0, merge 0
16:51:29.74   GAME -- Setting campaign state to 'coh'
16:51:29.74   GAME -- Closing state 'coh'
16:51:29.74   GAME -- Setting campaign state to 'cxp2'
16:51:29.74   GAME -- Closing state 'cxp2'
16:51:29.74   GAME -- Setting campaign state to 'cxp1'
16:51:29.74   GAME -- Closing state 'cxp1'
16:51:29.74   GAME -- Setting campaign state to 'dlc1'
16:51:29.74   GAME -- Closing state 'dlc1'
16:51:29.74   GAME -- Setting campaign state to 'dlc2'
16:51:29.74   GAME -- Closing state 'dlc2'
16:51:29.74   GAME -- Setting campaign state to 'dlc3'
16:51:29.74   GAME -- Closing state 'dlc3'
16:51:38.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:51:38.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:51:38.51   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:51:40.48   RNT_ReportPing: user 4946727:SZILU CANNOT connect pingMS=65535
16:51:42.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:51:42.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=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
16:51:42.21   Activating screen: OnlineGameSetup
16:51:42.21   Activating screen: RelicOnlineTabs
16:51:58.53   RNT_ReportPing: user 2725028:MaethCreed CANNOT connect pingMS=65535
16:52:12.59   Activating screen: RelicOnlineNewsScreen
16:52:12.59   Activating screen: RelicOnlineTabs
16:52:12.59   Activating screen: ScreenTipPopup
16:52:15.47   Activating screen: OnlineGameSetup
16:52:15.47   Activating screen: RelicOnlineTabs
16:52:23.00   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.2/0.0, #p/sec[s/r] = 1.3/0.9, max unsent 0, version err 0, merge 0
16:52:36.97   RNT_ReportPing: user 4715214:bobahty3 CANNOT connect pingMS=65535
16:52:43.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:52:43.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=0/0, frag=0/0, Errors=0/0
16:52:48.52   RNT_ReportPing: user 4961670:Xsdew CANNOT connect pingMS=65535
16:53:19.34   RNT_ReportPing: user 4841620:82NDAirbornFoxcomany CANNOT connect pingMS=65535
16:53:24.00   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 1.1/0.6, max unsent 0, version err 0, merge 0
16:53:44.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:53:44.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=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
16:53:46.09   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 806
16:53:46.09   Sent message game CompanyOfHeroes softthrottle
16:53:46.10   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:53:46.31   QueryMatches: Got [23] maps, [35] ids, [17] advertisements, startID [267982011]
16:53:46.31   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 10 matches
16:53:50.79   QueryMatches: Got [24] maps, [36] ids, [17] advertisements, startID [267983773]
16:53:57.56   Activating screen: RelicOnlineNewsScreen
16:53:57.56   Activating screen: RelicOnlineTabs
16:53:58.82   Activating screen: OnlineGameSetup
16:53:58.82   Activating screen: RelicOnlineTabs
16:53:59.37   Activating screen: GameHistory
16:53:59.37   Activating screen: RelicOnlineTabs
16:54:07.59   RNT_ReportPing: user 4915782:TheConcussionGrenade CANNOT connect pingMS=65535
16:54:12.76   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:54:12.80   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:54:13.92   RNT_ReportPing: user 4656415:Phantara CANNOT connect pingMS=65535
16:54:14.66   Activating screen: MessageBoxPopup
16:54:14.66   Session is marking the local peer PeerID 1 for death
16:54:14.66   OnlineLeaveAdvertisementAsync: Call to LeaveMatch id = 267983713, cancel = 1, badPids = 0, matchState = 0
16:54:14.66   Session::GetEvent DestroyPeer success Peer 1 removed
16:54:14.66   Session::DestroyPeer - deallocating peer 1
16:54:14.66   Session - Requested Disconnect for connectionID 2
16:54:14.66   Deallocated route ID=3 for PeerID 1 at WINaddr:192.168.0.2:6112;
16:54:14.66   Session - Requested Disconnect for connectionID 3
16:54:14.66   Closed route 3<>3 for PeerID 1 at WINaddr:192.168.0.2:6112;
16:54:14.66   AdvertisementInternal::Process - EVENT_DESTROYPEER
16:54:14.66   LeaveMatchInternal - no action taken, needLeave=0, service=0
16:54:14.66   Destroyed Matchinfo
16:54:14.97   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
16:54:14.97   PeerRemoveAll - flushing local session peer data
16:54:14.97   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
16:54:14.99   Activating screen: FEMovie
16:54:14.99   Activating screen: OnlineWidget
16:54:14.99   Activating screen: FE_mm_01
16:54:15.01   GAME -- Setting campaign state to 'coh'
16:54:15.01   GAME -- Closing state 'coh'
16:54:15.01   GAME -- Setting campaign state to 'cxp2'
16:54:15.01   GAME -- Closing state 'cxp2'
16:54:15.01   GAME -- Setting campaign state to 'cxp1'
16:54:15.01   GAME -- Closing state 'cxp1'
16:54:15.01   GAME -- Setting campaign state to 'dlc1'
16:54:15.01   GAME -- Closing state 'dlc1'
16:54:15.01   GAME -- Setting campaign state to 'dlc2'
16:54:15.01   GAME -- Closing state 'dlc2'
16:54:15.01   GAME -- Setting campaign state to 'dlc3'
16:54:15.01   GAME -- Closing state 'dlc3'
16:54:15.01   MessageInternal::DestroyChannel: Destroyed channel 534d5347
16:54:15.02   GAME -- Setting campaign state to 'coh'
16:54:15.02   GAME -- Closing state 'coh'
16:54:15.02   GAME -- Setting campaign state to 'cxp2'
16:54:15.02   GAME -- Closing state 'cxp2'
16:54:15.02   GAME -- Setting campaign state to 'cxp1'
16:54:15.02   GAME -- Closing state 'cxp1'
16:54:15.02   GAME -- Setting campaign state to 'dlc1'
16:54:15.02   GAME -- Closing state 'dlc1'
16:54:15.02   GAME -- Setting campaign state to 'dlc2'
16:54:15.02   GAME -- Closing state 'dlc2'
16:54:15.02   GAME -- Setting campaign state to 'dlc3'
16:54:15.02   GAME -- Closing state 'dlc3'
16:54:25.00   Transport - median kBPS [hi/cur] sent = 0.3/0.1, recvd = 0.3/0.1, #p/sec[s/r] = 0.5/0.4, max unsent 0, version err 0, merge 0
16:55:07.12   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:55:07.18   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
16:55:26.01   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.1/0.3, max unsent 0, version err 0, merge 0
16:55:40.96   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 801
16:55:40.96   Sent message game CompanyOfHeroes softthrottle
16:55:40.98   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:55:41.19   QueryMatches: Got [21] maps, [38] ids, [17] advertisements, startID [267983936]
16:55:41.19   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 5 matches
16:55:41.23   Activating screen: MessageBoxPopup
16:55:42.14   Sent message game CompanyOfHeroes disallowtraffic
16:55:42.14   Activating screen: FEMovie
16:55:42.14   Activating screen: OnlineWidget
16:55:42.14   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
16:55:42.14   Activating screen: OnlineSelectGame
16:55:42.20   GameSelectForm::Activate - hidden, proper tab not selected.
16:55:42.20   Activating screen: RelicOnlineChat
16:55:42.21   Activating screen: RelicOnlineNewsScreen
16:55:42.24   Activating screen: RelicOnlineStatsScreen
16:55:42.25   Activating screen: Achievements
16:55:42.25   GAME -- Setting campaign state to 'coh'
16:55:42.25   GAME -- Closing state 'coh'
16:55:42.25   GAME -- Setting campaign state to 'cxp2'
16:55:42.25   GAME -- Closing state 'cxp2'
16:55:42.25   GAME -- Setting campaign state to 'cxp1'
16:55:42.25   GAME -- Closing state 'cxp1'
16:55:42.25   GAME -- Setting campaign state to 'dlc1'
16:55:42.25   GAME -- Closing state 'dlc1'
16:55:42.26   GAME -- Setting campaign state to 'dlc2'
16:55:42.26   GAME -- Closing state 'dlc2'
16:55:42.26   GAME -- Setting campaign state to 'dlc3'
16:55:42.26   GAME -- Closing state 'dlc3'
16:55:42.26   GAME -- Setting campaign state to 'dlc1'
16:55:42.26   GAME -- Closing state 'dlc1'
16:55:42.27   Activating screen: GameHistory
16:55:42.27   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:55:42.27   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:55:42.27   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:55:42.27   Activating screen: OnlineSelectGame
16:55:42.27   Activating screen: RelicOnlineTabs
16:55:42.27   Activating screen: OnlineSelectGame
16:55:42.27   Activating screen: RelicOnlineTabs
16:55:42.27   Activating screen: MessageBoxPopup
16:55:42.27   ReplyToInvitationAsync: Call started.
16:55:42.27   JoinAsync: Starting AsyncJob...
16:55:42.27   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
16:55:42.27   Created Matchinfo
16:55:42.27   Got dlman msg [ack game CompanyOfHeroes disallowtraffic]
16:55:42.29   JoinChannelAsyncJob: Joining channel 17
16:55:42.29   OnlineJoinAsync: initiating FetchPublicURL
16:55:42.56   OnlineJoinAsync - RegisterLocalURLs public [udp:/address=101.165.4.59;port=6112;PID=4750714;RVCID=65579273], private [udp:/address=192.168.0.2;port=6112;PID=4750714]
16:55:42.78   GetPingAsync: Reporting Ping results for 5 players (success=5, fails=0)
16:55:45.81   Requesting Relic Downloader soft throttle call [GetAdvertisementsAsync] has time 801
16:55:45.81   Sent message game CompanyOfHeroes softthrottle
16:55:45.83   Got dlman msg [ack game CompanyOfHeroes softthrottle]
16:55:46.06   QueryMatches: Got [21] maps, [38] ids, [17] advertisements, startID [267977832]
16:55:46.06   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 5 matches
16:55:47.51   JoinMatch: lstStationURLsToProbe [udp:/address=124.150.69.7;port=6112;PID=2799203;RVCID=65579598]
16:55:47.51   JoinMatch: lstStationURLsToProbe [udp:/address=192.168.1.70;port=6112;PID=2799203;RVCID=65579598]
16:55:47.52   JoinMatch: initiating ProbeStations against 1 users, 2 URLS:
16:55:48.25   JoinMatch - Reporting Ping results for 1 players (good:1, bad:0, unknown: 0)
16:55:48.52   OnlineJoinAsync success
16:55:48.52   OnJoinAdvertisementSuccess - joined online match, server leave notification required
16:55:48.53   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
16:55:48.53   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
16:55:48.53   Allocated route ID=0 for PeerID 0 at WINaddr:192.168.0.2:6112;
16:55:48.53   Transport::OpenInternal request to WINaddr:192.168.0.2:6112;
16:55:48.53   Allocated route ID=0 for PeerID 0 at WINaddr:124.150.69.7:6112;
16:55:48.53   Transport::OpenInternal request to WINaddr:124.150.69.7:6112;
16:55:48.53   Session::Connect sid = FF91C40, hostURL = WINaddr:124.150.69.7:6112;, local addresses = WINaddr:192.168.0.2:6112;
16:55:48.55   Transport - Largest sent is now 336
16:55:48.64   Transport - Largest received is now 1235
16:55:48.65   Session::PollTransportEvents AddPeerMessage from peer 0 recv'd for existing pending peer 0, the local peer
16:55:48.65   Session - highest peerID now : 2
16:55:48.65   Session::PollTransportEvents AddPeerMessage from peer 0 recv'd for existing pending peer 0, the host
16:55:48.65   ValidateCustomData: called with 432 bytes of custom data
16:55:48.65   Client accepted Peer 2 into the match at address list = WINaddr:192.168.0.2:6112;, routes = WINaddr:192.168.0.2:6112;
16:55:48.65   Session::GetEvent - probing service provided NAT'd routes
16:55:48.65   AdvertisementInternal::Process - EVENT_NEWPEER
16:55:48.65   ValidateCustomData: called with 435 bytes of custom data
16:55:48.65   Client accepted Peer 1 into the match at address list = WINaddr:192.168.1.70:6112;, routes = WINaddr:124.150.69.7:6112;
16:55:48.65   AdvertisementInternal::Process - EVENT_NEWPEER
16:55:48.65   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
16:55:48.65   Accepted matchInfo updated 93 from host
16:55:48.65   Accepted matchInfo updated 95 from host
16:55:48.67   JoinAsync - got operation info 0:SessionOperationInfo::SUCCESS
16:55:48.68   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
16:55:48.68   JoinAsync - completed with JoinResult = 0:JoinEvent::R_Success
16:55:48.68   Activating screen: OnlineGameSetup
16:55:48.68   Activating screen: RelicOnlineTabs
16:55:48.68   AutomatchInternal::OnJoinComplete - Completed Join with success=1
16:55:48.68   AutomatchInternal::OnJoinComplete - automatcher is no longer active - ignoring
16:55:48.68   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
16:55:48.68   JoinAsync: AsyncJob Complete...
16:55:48.69   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:55:48.69         host PeerID 1 CONN ack=  0 (  0ms~0) unack=  4, retry=  0, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=45ms) 100.00%, pending=0, dead=0
16:55:48.69   MessageCounts: inval=0/0, seek=0/0, join=1/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/2, remove=0/0, drop=0/0, data=2/2, voice=0/0, rchk=1/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=3/3, frag=0/0, Errors=0/0
16:55:48.70   MessageInternal::CreateChannel: Created channel 534d5347
16:55:48.70   Map 6p_leningrad does not have a valid operation type
16:55:48.70   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:48.83   Accepted matchInfo updated 97 from host
16:55:48.84   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:48.85   Transport - Largest sent is now 413
16:55:48.87   Accepted matchInfo updated 99 from host
16:55:48.94   Accepted matchInfo updated 102 from host
16:55:50.82   QueryMatches: Got [23] maps, [40] ids, [17] advertisements, startID [267983783]
16:55:51.32   Accepted matchInfo updated 103 from host
16:55:51.33   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:51.36   Accepted matchInfo updated 104 from host
16:55:51.42   Accepted matchInfo updated 106 from host
16:55:54.06   Accepted matchInfo updated 107 from host
16:55:54.06   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:54.18   Accepted matchInfo updated 110 from host
16:55:55.64   Accepted matchInfo updated 111 from host
16:55:55.65   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:55.67   Accepted matchInfo updated 112 from host
16:55:55.74   Accepted matchInfo updated 114 from host
16:55:58.45   Accepted matchInfo updated 115 from host
16:55:58.45   GetMaxFrameTimeFromProfile: players=6 expected FPS=29.497877, bars=5, max avg=0.025, sd=0.009, 5 samples =  0.02 0.03 0.04 0.02 0.02
16:55:58.47   Accepted matchInfo updated 116 from host
16:55:58.56   Accepted matchInfo updated 118 from host
16:56:19.68   Accepted matchInfo updated 119 from host
16:56:24.95   SMT_StartGame_To_Everyone - received
16:56:24.95   SMT_StartGame_To_Everyone - Starting game with synclevel 0 from start message
16:56:24.95   GameSetupForm - Starting game
16:56:24.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:56:24.95   PopulateGameInfo - random seed:[1353653778], guid:[{3394b53d-67d9-491d-8f81-6c9a15931270}], sync level:[0]
16:56:24.96   Error loading [DATA:levelingCurve.lua]
16:56:24.96   Error loading [DATA:levelingCurve.lua]
16:56:24.97   Error loading [DATA:levelingCurve.lua]
16:56:24.97   Error loading [DATA:levelingCurve.lua]
16:56:24.97   MOD - Setting player (0) race to: allies
16:56:24.97   MOD - Setting player (0) race to: 1
16:56:24.97   MOD - Setting player (1) race to: allies
16:56:24.97   MOD - Setting player (1) race to: 1
16:56:24.97   MOD - Setting player (2) race to: axis
16:56:24.97   MOD - Setting player (2) race to: 3
16:56:24.97   MOD - Setting player (3) race to: axis
16:56:24.97   MOD - Setting player (3) race to: 3
16:56:24.98   APP -- Game Start
16:56:24.98   Sent message game CompanyOfHeroes disallowtraffic
16:56:24.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:56:24.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:56:24.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
16:56:24.98   MessageInternal::DestroyChannel: Destroyed channel 534d5347
16:56:24.99   GAME -- Ending FE
16:56:24.99   UIFrontEnd - Unloading Front End
16:56:25.00   SOUND -- Shutting down ...
16:56:25.06   SOUND -- Shutdown completed!
16:56:25.07   MessageInternal::CreateChannel: Created channel 53594e43
16:56:25.07   P2PNetworkSynchronization::RegisterPeer - info, registered remote station [1].
16:56:25.07   P2PNetworkSynchronization::RegisterPeer - info, registered local station [2].
16:56:25.07   MessageInternal::CreateChannel: Created channel 474f424a
16:56:25.07
16:56:25.07   GAME -- *** Beginning mission 6p_leningrad (2 Humans, 2 Computers) ***
16:56:25.07
16:56:25.07   GameObj::StartGameObj - info, network session GUID set to [{3394b53d-67d9-491d-8f81-6c9a15931270}].
16:56:25.17   GAME -- Recording game
16:56:25.21   Activating screen: GameLoadScreen
16:56:25.21   Got dlman msg [ack game CompanyOfHeroes disallowtraffic]
16:56:25.21   Accepted matchInfo updated 124 from host
16:56:25.21   AutomatchInternal::OnStartComplete - detected successful game start
16:56:25.21   Accepted matchInfo updated 127 from host
16:56:25.21   GameObjController - OnMatchEvent: event type 4
16:56:25.38   OnlineUpdateStateAsync: initiating state change, id = 267983936, state=2
16:56:25.73   OnlineUpdateStateAsync [(4:1) PythonCoreErrorCode::Exception]
16:56:25.74   THREAD: Hyper-Threading Technology Processors are not detected.
16:56:25.80   SOUND -- Initializing ...
16:56:26.12   SOUND -- Initialization completed!
16:56:26.14   PHYSICS: detected processor(s) capable of handling 8 threads.
16:56:26.17   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\6p_leningrad'
16:56:26.17   MOD -- Using Mod 'Eastern_Front'
16:56:26.17   Unable to load/parse precache file [DATA:scenarios\mp\6p_leningrad_precache.lua]
16:56:26.27   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
16:56:26.28   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
16:56:27.01   Transport - median kBPS [hi/cur] sent = 1.6/0.1, recvd = 2.1/0.3, #p/sec[s/r] = 2.0/1.5, max unsent 0, version err 0, merge 21
16:56:27.33   GameObjLoader - upgrading load_count from 0 to 1108
16:56:28.71   GameObjLoader - upgrading load_count from 1108 to 1852
16:56:28.77   PHYSICS -- Created node factory 'HVOK'
16:56:28.77   PHYSICS -- Created node factory 'DMMY'
16:56:33.45   RENDER ANIM -- TextureLoad: Data:art\models\races\axis\shared_textures\tex_ax_tiger_ace_dlc_maps_diff.rgt: Not a valid chunky file!
16:56:35.55   TGAIO -- TGA file 'Data:art\models\races\russians\projectiles\rocket_300mm\model\reference_diffuse.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
16:56:36.12   GameObjLoader - upgrading load_count from 1330 to 4080
16:56:36.88   RNT_ReportPing: user 4441577:TehPlazmon CANNOT connect pingMS=65535
16:56:38.00   GameObjLoader 10a32950 - resetting counters
16:56:38.00   GameObjLoader 10a32950 - LOAD_DONE
16:56:38.00   GAME - SessionSetup
16:56:38.14   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
16:56:38.14   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
16:56:38.14   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
16:56:41.76   GAME - CreateGEWorld in 3758 ms
16:56:41.76   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
16:56:41.95   GAME - SessionSetup finished in 3951 ms
16:56:41.96   GAME - WaterReflectionManagerSetup
16:56:41.96   GAME - WaterReflectionManagerSetup finished in 0 ms
16:56:42.29   MessageInternal::CreateChannel: Created channel 474d4343
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x0786e570
[Cope FactionFix] - patched @0x62245491
[Cope FactionFix] - hooked @0x62245492
[Cope FactionFix] - patched @0x62244a07
[Cope FactionFix] - hooked @0x62244a07
[Cope FactionFix] - Hook installed
16:56:42.37   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilate.scar] succeeded.
16:56:42.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
16:56:42.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
16:56:42.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
16:56:42.90   MOD -- Player  (unused player) (frame 0) (KillPlayer)
16:56:43.07   MessageInternal::CreateChannel: Created channel 4d4f444d
16:56:43.46   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
16:56:43.47   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
16:56:43.47   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
16:56:43.47   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
16:56:43.47   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
16:56:43.72   SPEECHMANAGER -- Loaded in 0.231850 seconds
16:56:44.31   GameObjLoader - upgrading load_count from 0 to 216
16:56:44.61   GameObjLoader 10a32ab0 - resetting counters
16:56:44.61   GameObjLoader 10a32ab0 - LOAD_DONE
16:56:44.96   PreloadResources took 0ms.
16:56:44.98   GAME -- Loading completed (20 seconds)
16:56:44.98   SIM -- Setting SyncErrorChecking level to Low
16:56:44.99   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:4750714, diegobah]" finished loading with checksum [3709254612].
16:56:46.00   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:4750714, diegobah]" finished loading with checksum [3709254612].
16:56:46.25   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:2799203, satyr69]" finished loading with checksum [3709254612].
16:56:46.26   Activating screen: GameScreen
16:56:46.26   Activating screen: Decorators_widescreen
16:56:46.26   Activating screen: Taskbar_widescreen
16:56:46.26   Activating screen: SubtitleScreen
16:56:46.26   Activating screen: TextOverlayScreen
16:56:46.31   PerformanceRecorder::StartRecording for game size 4
16:56:46.31   GAME -- Starting mission...
16:56:46.82   We do not have a sync command for peer=1, currentframe=0
16:56:47.13   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 2) (CmdAI)
16:56:47.51   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 5) (CmdAI)
16:56:49.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:56:49.01         host PeerID 1 CONN ack=  0 (148ms~0) unack=  1, retry=  3, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=63ms) 97.14%, pending=0, dead=0
16:56:49.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=98/90, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=13/17, frag=0/0, Errors=0/0
16:56:51.62   Warning: non auto-match upgrade not found in AE, tuning
16:57:01.29   Warning: binding repeat_1(Ability: abilities\reenable_capture_ability_engineers) -- ui index '0' out of bounds; range is [1, 12]
16:57:01.29   Warning: binding repeat_3(Ability: abilities\smg_m3) -- ui index '0' out of bounds; range is [1, 12]
16:57:28.01   Transport - median kBPS [hi/cur] sent = 1.6/0.4, recvd = 2.1/0.5, #p/sec[s/r] = 7.9/7.9, max unsent 0, version err 0, merge 14
16:57:50.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:57:50.01         host PeerID 1 CONN ack=  0 (185ms~0) unack=  1, retry= 44, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=151ms) 93.06%, pending=0, dead=0
16:57:50.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=488/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
16:57:55.59   We do not have a sync command for peer=1, currentframe=551
16:57:55.73   We do not have a sync command for peer=1, currentframe=551
16:58:03.86   We do not have a sync command for peer=1, currentframe=617
16:58:04.00   We do not have a sync command for peer=1, currentframe=617
16:58:04.11   We do not have a sync command for peer=1, currentframe=617
16:58:04.11   P2PNetworkSynchronization::GetSyncCommand - warning, no sync command from  station [1], current lag complaint threshold = [2] frames.
16:58:04.25   We do not have a sync command for peer=1, currentframe=617
16:58:29.01   Transport - median kBPS [hi/cur] sent = 1.6/0.6, recvd = 2.1/0.7, #p/sec[s/r] = 8.7/8.8, max unsent 0, version err 0, merge 2
16:58:41.51   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
16:58:51.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:58:51.00         host PeerID 1 CONN ack=  0 (188ms~0) unack=  2, retry= 61, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=53ms) 94.65%, pending=0, dead=0
16:58: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=489/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
16:59:30.00   Transport - median kBPS [hi/cur] sent = 1.6/0.6, recvd = 2.1/0.5, #p/sec[s/r] = 9.0/9.1, max unsent 0, version err 0, merge 3
16:59:52.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
16:59:52.01         host PeerID 1 CONN ack=  0 (204ms~0) unack=  2, retry= 91, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=51ms) 94.51%, pending=0, dead=0
16:59:52.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=488/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
17:00:31.01   Transport - median kBPS [hi/cur] sent = 1.6/0.4, recvd = 2.1/0.6, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 1
17:00:53.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:00:53.00         host PeerID 1 CONN ack=  0 (189ms~0) unack=  2, retry= 96, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=73ms) 95.54%, pending=0, dead=0
17:00: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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:01:32.01   Transport - median kBPS [hi/cur] sent = 1.6/0.6, recvd = 2.1/0.7, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 2
17:01:43.85   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:01:44.74   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:01:54.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:01:54.01         host PeerID 1 CONN ack=  0 (167ms~0) unack=  1, retry= 97, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=55ms) 96.33%, pending=0, dead=0
17:01: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=488/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
17:02:27.47   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:02:33.00   Transport - median kBPS [hi/cur] sent = 1.6/0.5, recvd = 2.1/0.6, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 0
17:02:50.10   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:02:55.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:02:55.01         host PeerID 1 CONN ack=  0 (196ms~0) unack=  1, retry=108, highwaterOOS=0 @WINaddr:124.150.69.7:6112; (ping=66ms) 96.56%, pending=0, dead=0
17:02:55.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=488/489, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
17:03:00.09   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:03:12.72   We do not have a sync command for peer=1, currentframe=3088
17:03:23.97   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:03:34.01   Transport - median kBPS [hi/cur] sent = 1.6/0.5, recvd = 2.1/0.4, #p/sec[s/r] = 8.6/8.6, max unsent 0, version err 0, merge 6
17:03:56.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:03:56.01         host PeerID 1 CONN ack=  0 (178ms~0) unack=  1, retry=116, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=72ms) 96.81%, pending=0, dead=0
17:03:56.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=488/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
17:04:35.00   Transport - median kBPS [hi/cur] sent = 1.6/0.4, recvd = 2.1/0.5, #p/sec[s/r] = 8.6/8.8, max unsent 0, version err 0, merge 1
17:04:36.09   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:04:57.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:04:57.00         host PeerID 1 CONN ack=  0 (176ms~0) unack=  1, retry=123, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=60ms) 97.02%, pending=0, dead=0
17:04: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=488/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
17:04:59.44   Activating screen: Command_Tree
17:05:03.10   Activating screen: Command_Branch
17:05:06.54   Activating screen: NewObjective_widescreen
17:05:36.00   Transport - median kBPS [hi/cur] sent = 1.6/0.9, recvd = 2.1/0.6, #p/sec[s/r] = 8.6/8.6, max unsent 0, version err 0, merge 2
17:05:41.71   We do not have a sync command for peer=1, currentframe=4280
17:05:52.86   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:05:58.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:05:58.00         host PeerID 1 CONN ack=  0 (195ms~0) unack=  1, retry=136, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=58ms) 97.06%, pending=0, dead=0
17:05: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=488/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
17:06:22.08   Warning: binding repeat_3(Production Item: sbps\races\allies\vehicles\m3_halftrack_squad_with_engineer) -- ui index '0' out of bounds; range is [1, 12]
17:06:37.00   Transport - median kBPS [hi/cur] sent = 1.6/0.9, recvd = 2.1/0.6, #p/sec[s/r] = 8.6/8.6, max unsent 0, version err 0, merge 0
17:06:59.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:06:59.01         host PeerID 1 CONN ack=  0 (180ms~0) unack=  1, retry=145, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=61ms) 97.17%, pending=0, dead=0
17:06:59.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:07:20.82   We do not have a sync command for peer=1, currentframe=5073
17:07:25.20   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:07:38.01   Transport - median kBPS [hi/cur] sent = 1.6/0.9, recvd = 2.1/0.5, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 0
17:08:00.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:08:00.01         host PeerID 1 CONN ack=  0 (153ms~0) unack=  1, retry=150, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=55ms) 97.33%, pending=0, dead=0
17:08:00.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:08:13.92   Unabled to do upgrade, CANPRODUCE_NoResources
17:08:39.00   Transport - median kBPS [hi/cur] sent = 1.6/0.8, recvd = 2.1/0.5, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 2
17:09:01.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:09:01.00         host PeerID 1 CONN ack=  0 (164ms~0) unack=  1, retry=161, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=58ms) 97.37%, pending=0, dead=0
17:09:01.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:09:02.54   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:09:02.54   Muting spew of bind updater.  Fix art and re-test.
17:09:25.08   Activating screen: Command_Branch
17:09:27.62   Activating screen: NewObjective_widescreen
17:09:40.01   Transport - median kBPS [hi/cur] sent = 1.6/0.8, recvd = 2.1/0.4, #p/sec[s/r] = 8.6/8.6, max unsent 0, version err 0, merge 2
17:10:02.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:10:02.00         host PeerID 1 CONN ack=  0 (174ms~0) unack=  1, retry=180, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=74ms) 97.28%, pending=0, dead=0
17:10:02.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=488/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
17:10:41.01   Transport - median kBPS [hi/cur] sent = 1.6/0.9, recvd = 2.1/0.6, #p/sec[s/r] = 9.0/9.0, max unsent 0, version err 0, merge 0
17:11:03.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:11:03.01         host PeerID 1 CONN ack=  0 (178ms~0) unack=  1, retry=220, highwaterOOS=2 @WINaddr:124.150.69.7:6112; (ping=67ms) 96.93%, pending=0, dead=0
17:11:03.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=488/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
17:11:17.80   We do not have a sync command for peer=1, currentframe=6969
17:11:17.92   We do not have a sync command for peer=1, currentframe=6969
17:11:18.05   We do not have a sync command for peer=1, currentframe=6969
17:11:18.05   P2PNetworkSynchronization::GetSyncCommand - warning, no sync command from  station [1], current lag complaint threshold = [2] frames.
17:11:42.00   Transport - median kBPS [hi/cur] sent = 1.6/0.9, recvd = 2.1/0.4, #p/sec[s/r] = 9.4/8.9, max unsent 0, version err 0, merge 3
17:12:04.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:12:04.00         host PeerID 1 CONN ack=  0 (154ms~0) unack=  2, retry=259, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=51ms) 96.63%, pending=0, dead=0
17:12:04.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/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:12:43.01   Transport - median kBPS [hi/cur] sent = 1.6/1.0, recvd = 2.1/0.4, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 2
17:13:05.02   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:13:05.02         host PeerID 1 CONN ack=  0 (185ms~0) unack=  1, retry=264, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=68ms) 96.77%, pending=0, dead=0
17:13:05.02   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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
17:13:44.00   Transport - median kBPS [hi/cur] sent = 1.6/1.1, recvd = 2.1/0.5, #p/sec[s/r] = 8.7/8.8, max unsent 0, version err 0, merge 3
17:14:04.30   We do not have a sync command for peer=1, currentframe=8301
17:14:06.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:14:06.01         host PeerID 1 CONN ack=  0 (179ms~0) unack=  1, retry=278, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=57ms) 96.80%, pending=0, dead=0
17:14:06.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
17:14:07.38   We do not have a sync command for peer=1, currentframe=8325
17:14:27.94   FXShader::SetVariable( "tex0", T ) uses unknown variable.
17:14:45.01   Transport - median kBPS [hi/cur] sent = 1.6/1.4, recvd = 2.1/0.5, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 3
17:14:53.18   We do not have a sync command for peer=1, currentframe=8692
17:15:07.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:15:07.00         host PeerID 1 CONN ack=  0 (176ms~0) unack=  1, retry=290, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=59ms) 96.84%, pending=0, dead=0
17:15:07.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:15:46.00   Transport - median kBPS [hi/cur] sent = 1.7/1.1, recvd = 2.1/0.7, #p/sec[s/r] = 8.6/8.7, max unsent 0, version err 0, merge 3
17:16:01.56   We do not have a sync command for peer=1, currentframe=9239
17:16:08.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:16:08.01         host PeerID 1 CONN ack=  0 (183ms~0) unack=  1, retry=293, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=64ms) 96.97%, pending=0, dead=0
17:16:08.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:16:10.20   We do not have a sync command for peer=1, currentframe=9308
17:16:47.00   Transport - median kBPS [hi/cur] sent = 1.7/1.2, recvd = 2.1/0.6, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 0
17:17:09.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:17:09.01         host PeerID 1 CONN ack=  0 (181ms~0) unack=  1, retry=299, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=57ms) 97.06%, pending=0, dead=0
17:17:09.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:17:39.94   We do not have a sync command for peer=1, currentframe=10026
17:17:44.84   We do not have a sync command for peer=1, currentframe=10065
17:17:48.00   Transport - median kBPS [hi/cur] sent = 1.7/1.0, recvd = 2.1/0.6, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 2
17:18:10.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:18:10.01         host PeerID 1 CONN ack=  0 (176ms~0) unack=  1, retry=301, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=60ms) 97.17%, pending=0, dead=0
17:18:10.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:18:49.01   Transport - median kBPS [hi/cur] sent = 1.7/1.1, recvd = 2.1/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 0
17:19:05.10   Activating screen: Command_Branch
17:19:11.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:19:11.00         host PeerID 1 CONN ack=  0 (193ms~0) unack=  1, retry=305, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=55ms) 97.26%, pending=0, dead=0
17:19:11.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:19:24.58   We do not have a sync command for peer=1, currentframe=10863
17:19:50.01   Transport - median kBPS [hi/cur] sent = 1.7/1.1, recvd = 2.1/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 3
17:20:12.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:20:12.01         host PeerID 1 CONN ack=  0 (170ms~0) unack=  1, retry=309, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=67ms) 97.34%, pending=0, dead=0
17:20:12.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:20:51.00   Transport - median kBPS [hi/cur] sent = 1.7/1.1, recvd = 2.1/0.6, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 0
17:21:13.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:21:13.01         host PeerID 1 CONN ack=  0 (157ms~0) unack=  1, retry=309, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=55ms) 97.45%, pending=0, dead=0
17:21:13.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:21:15.88   Warning: binding repeat_2(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
17:21:52.00   Transport - median kBPS [hi/cur] sent = 1.7/1.1, recvd = 2.1/0.5, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 0
17:22:14.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:22:14.00         host PeerID 1 CONN ack=  0 (170ms~0) unack=  1, retry=311, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=53ms) 97.53%, pending=0, dead=0
17:22:14.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=488/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
17:22:53.01   Transport - median kBPS [hi/cur] sent = 1.7/0.9, recvd = 2.1/0.5, #p/sec[s/r] = 8.4/8.4, max unsent 0, version err 0, merge 6
17:23:14.81   We do not have a sync command for peer=1, currentframe=12705
17:23:15.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:23:15.00         host PeerID 1 CONN ack=  0 (160ms~0) unack=  1, retry=313, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=60ms) 97.61%, pending=0, dead=0
17:23:15.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:23:18.00   We do not have a sync command for peer=1, currentframe=12730
17:23:38.57   We do not have a sync command for peer=1, currentframe=12895
17:23:54.01   Transport - median kBPS [hi/cur] sent = 1.7/1.2, recvd = 2.1/0.6, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 8
17:24:03.82   We do not have a sync command for peer=1, currentframe=13097
17:24:16.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:24:16.00         host PeerID 1 CONN ack=  0 (178ms~0) unack=  1, retry=315, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=57ms) 97.68%, pending=0, dead=0
17:24:16.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
17:24:36.68   We do not have a sync command for peer=1, currentframe=13360
17:24:36.81   We do not have a sync command for peer=1, currentframe=13360
17:24:55.00   Transport - median kBPS [hi/cur] sent = 1.7/1.0, recvd = 2.1/0.6, #p/sec[s/r] = 8.6/8.6, max unsent 0, version err 0, merge 3
17:25:15.68   Activating screen: Command_Branch
17:25:17.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:25:17.01         host PeerID 1 CONN ack=  0 (178ms~0) unack=  2, retry=323, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=61ms) 97.71%, pending=0, dead=0
17:25:17.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/492, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/18, frag=0/0, Errors=0/0
17:25:18.23   Activating screen: NewObjective_widescreen
17:25:56.00   Transport - median kBPS [hi/cur] sent = 1.7/1.0, recvd = 2.1/0.9, #p/sec[s/r] = 8.8/8.6, max unsent 0, version err 0, merge 17
17:26:18.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:26:18.00         host PeerID 1 CONN ack=  0 (189ms~0) unack=  2, retry=347, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=59ms) 97.62%, pending=0, dead=0
17:26: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=488/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:26:57.00   Transport - median kBPS [hi/cur] sent = 1.7/0.8, recvd = 2.1/0.6, #p/sec[s/r] = 8.9/8.9, max unsent 0, version err 0, merge 5
17:27:19.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:27:19.01         host PeerID 1 CONN ack=  0 (171ms~0) unack=  2, retry=371, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=95ms) 97.55%, pending=0, dead=0
17:27:19.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=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:27:58.00   Transport - median kBPS [hi/cur] sent = 1.7/0.8, recvd = 2.1/0.6, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 3
17:28:20.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:28:20.00         host PeerID 1 CONN ack=  0 (152ms~0) unack=  1, retry=378, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=72ms) 97.58%, pending=0, dead=0
17:28:20.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=487/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
17:28:59.00   Transport - median kBPS [hi/cur] sent = 1.7/0.9, recvd = 2.1/0.6, #p/sec[s/r] = 8.4/8.5, max unsent 0, version err 0, merge 0
17:29:21.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:29:21.01         host PeerID 1 CONN ack=  0 (161ms~0) unack=  2, retry=413, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=70ms) 97.44%, pending=0, dead=0
17:29: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=489/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
17:29:42.25   We do not have a sync command for peer=1, currentframe=15805
17:30:00.01   Transport - median kBPS [hi/cur] sent = 1.7/1.0, recvd = 2.1/0.6, #p/sec[s/r] = 8.7/8.6, max unsent 0, version err 0, merge 22
17:30:22.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:30:22.00         host PeerID 1 CONN ack=  0 (179ms~0) unack=  2, retry=415, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=64ms) 97.50%, pending=0, dead=0
17:30: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=488/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
17:31:01.00   Transport - median kBPS [hi/cur] sent = 1.7/0.8, recvd = 2.1/0.5, #p/sec[s/r] = 8.7/8.4, max unsent 0, version err 0, merge 18
17:31:01.45   Activating screen: Command_Branch
17:31:23.00   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:31:23.00         host PeerID 1 CONN ack=  0 (185ms~0) unack=  2, retry=417, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=55ms) 97.56%, pending=0, dead=0
17:31:23.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=488/488, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
17:32:02.00   Transport - median kBPS [hi/cur] sent = 1.7/0.8, recvd = 2.1/1.1, #p/sec[s/r] = 9.1/8.4, max unsent 0, version err 0, merge 16
17:32:24.01   local      PeerID 2 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.2:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:32:24.01         host PeerID 1 CONN ack=  0 (189ms~0) unack=  3, retry=423, highwaterOOS=3 @WINaddr:124.150.69.7:6112; (ping=59ms) 97.60%, pending=0, dead=0
17:32: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=488/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
17:32:35.96   Network - Sync Error Detected - Frame=17196 Checksum: usi=2 (B99478A2) != ulGoodId=1 (CE4ECD9)
17:32:35.96   Network - Requested CRC Block dump for frames [17195] and [17194]
17:32:35.96   SIM -- Dumping SyncErrorlog for frame 17194
17:32:35.97   SIM -- Dumping SyncErrorlog for frame 17193
17:32:35.97   SIM -- Dumping SyncErrorlog for frame 17192
17:32:35.98   SIM -- Dumping SyncErrorlog for frame 17191
17:32:35.98   SIM -- Dumping SyncErrorlog for frame 17190
17:32:35.99   SIM -- Dumping SyncErrorlog for frame 17189
17:32:35.99   SIM -- Dumping SyncErrorlog for frame 17188
17:32:36.00   SIM -- Dumping SyncErrorlog for frame 17187
17:32:36.00   SIM -- Dumping SyncErrorlog for frame 17186
17:32:36.00   SIM -- Dumping SyncErrorlog for frame 17185
17:32:36.01   SIM -- Dumping SyncErrorlog for frame 17184
17:32:36.01   SIM -- Dumping SyncErrorlog for frame 17183
17:32:36.02   SIM -- Dumping SyncErrorlog for frame 17195
17:32:36.02   Session::ProcessPeerMessages LocalPeer is marking PeerID 1 for death on instruction from Host
17:32:36.02   Session::ProcessPeerMessages LocalPeer is marking PeerID 2 for death on instruction from Host
17:32:36.02   GAME -- simulation is out of sync, reporting station id = [2],frame number= [17196], local frame number =[17195]
17:32:36.02   GAME -- Frame 17195 - SimKillPlayer - Destroying SimPlayer 1001, reason 3
17:32:36.02   MOD -- Player diegobah (network out of sync) (frame 17195) (KillPlayer)
17:32:36.02   GameApp::ReportStats
17:32:36.02   ReportMatchResults - onlyReportOnWin = 0
17:32:36.02   ReportMatchResults - game ended with result ER_SYNC_ERROR_WHILE_LOADING or ER_SYNC_ERROR
17:32:36.02   ReportMatchResults - detected out of sync player
17:32:36.02   ReportMatchResults - SimID:1000, raceID:0, teamID:0, uid:0:2799203, result:5:PS_WON
17:32:36.02   ReportMatchResults - SimID:1001, raceID:0, teamID:0, uid:0:4750714, result:5:PS_WON
17:32:36.02   ReportMatchResults - SimID:1003, raceID:1, teamID:1, uid:0:0, AI player, ignoring
17:32:36.02   ReportMatchResults - SimID:1004, raceID:1, teamID:1, uid:0:0, AI player, ignoring
17:32:36.02   ReportMatchResults - forcing arbitration for match 0:267983936
17:32:36.02   ReportSimStats - storing simulation results for match 0:267983936
17:32:36.02   ReportStats - ReportSimStats failed
17:32:36.07   GameApp::ReportStats
17:32:36.07   ReportStats - already reported for match 0:267983936
17:32:36.07   GameObj::DoGameOverPopup
17:32:36.07   Activating screen: GameOverDialog
17:32:36.07   GameOverDialog - ending with sync errors
17:32:36.07   Disconnect process already running
17:32:36.07   Session is marking PeerID 1 for death
17:32:36.07   Session is marking the local peer PeerID 2 for death
17:32:36.07   OnlineLeaveAdvertisementAsync: Match id = 267983936 started, nothing to do
17:32:36.07   OnlineReportResults - player=2799203, gid = 267983936, time=2149, result=5, team=0, race=0, simPlayerID=1000
17:32:36.07   OnlineReportResults - player=4750714, gid = 267983936, time=2149, result=5, team=0, race=0, simPlayerID=1001
17:32:36.07   OnlineReportResults: Call started.
17:32:36.07   P2PNetworkSynchronization::OnConsolidationVote - got consolidation vote from [1] gmcv.frameNo=17197, gmcv.usi=2
17:32:36.07   P2PNetworkSynchronization::StartConsolidation - info, starting consolidation for station, station id= [2].
17:32:36.07   P2PNetworkSynchronization::Vote - info, voting for consolidation, station id = [2], frame = [17198]
17:32:36.07   Session::SendData local peer is marked for death, not sending
17:32:36.07   Consolidation - cannot find station, id= [2] in vote map.
17:32:36.07   Consolidation - station, id= [2] is not done, lowest [4294967295] highest [0],[1] total voters.
17:32:36.07   Session::SendData local peer is marked for death, not sending
17:32:36.07   P2PNetworkSynchronization::CheckConsolidation - info, consolidation for station [2] completed.
17:32:36.07   Session::GetEvent DestroyPeer success Peer 1 removed
17:32:36.07   Session::DestroyPeer - deallocating peer 1
17:32:36.07   Session - Requested Disconnect for connectionID 5
17:32:36.07   Deallocated route ID=7 for PeerID 1 at WINaddr:124.150.69.7:6112;
17:32:36.07   Session - Requested Disconnect for connectionID 7
17:32:36.07   Closed route 7<>5 for PeerID 1 at WINaddr:124.150.69.7:6112;
17:32:36.07   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:32:36.07   P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [1].
17:32:36.07   OnDestroyedPeerEvent - calling RemovePeerFromSync.
17:32:36.07   P2PNetworkSynchronization::StartConsolidation - info, starting consolidation for station, station id= [1].
17:32:36.07   P2PNetworkSynchronization::StartConsolidation - peer rec state not connected, station id= [2].
17:32:36.07   P2PNetworkSynchronization::Vote - info, voting for consolidation, station id = [1], frame = [17198]
17:32:36.07   Session::SendData local peer is marked for death, not sending
17:32:36.07   Consolidation - cannot find station, id= [2] in vote map.
17:32:36.07   Consolidation - no voter, consolidation done
17:32:36.07   P2PNetworkSynchronization::CheckConsolidation - info, consolidation for station [1] completed.
17:32:36.07   GameObjController - OnMatchEvent: event type 1
17:32:36.07   GameObj::OnRemoteStationDisconnected - info, remote station [1] was disconnected reason 1
17:32:36.07   GameObj::OnRemoteStationDisconnected - info, remote station [1] is disconnected
17:32:36.07   onplayerdrops 1000 0
17:32:36.07   ArbitrationMessageNeeded OnPlayerDrops adding player 1000 [2799203] as victorious ally
17:32:36.07   Session::GetEvent DestroyPeer success Peer 2 removed
17:32:36.07   Session::DestroyPeer - deallocating peer 2
17:32:36.07   Session - Requested Disconnect for connectionID 4
17:32:36.07   Deallocated route ID=6 for PeerID 2 at WINaddr:192.168.0.2:6112;
17:32:36.07   Session - Requested Disconnect for connectionID 6
17:32:36.07   Closed route 6<>6 for PeerID 2 at WINaddr:192.168.0.2:6112;
17:32:36.07   AdvertisementInternal::Process - EVENT_DESTROYPEER
17:32:36.07   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:32:36.07   P2PNetworkSynchronization::OnDestroyedPeerEvent - info, drop event received for remote station [2].
17:32:36.07   OnDestroyedPeerEvent - calling RemovePeerFromSync.
17:32:36.07   P2PNetworkSynchronization::StartConsolidation - queue->GetState() != ReceiveQueue::STATE_CONNECTED 2
17:32:36.07   GameObjController - OnMatchEvent: event type 1
17:32:36.07   GAME -- Frame 17196 - SimKillPlayer - Destroying SimPlayer 1001, reason 2
17:32:36.07   GameApp::ReportStats
17:32:36.07   ReportStats - already reported for match 0:267983936
17:32:36.07   LeaveMatchInternal - no action taken, needLeave=0, service=0
17:32:36.07   Destroyed Matchinfo
17:32:36.10   OnlineLeaveAdvertisementAsync: Match id = 267983936 started, nothing to do
17:32:36.12   PeerRemoveAll - flushing local session peer data
17:32:36.12   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:32:36.46   RendezvousNotifier - StatsUpdate MatchEvent - flushing player stats for pid = 4750714.
17:32:36.46   RNT_StatsUpdate: Win notification, userID [00000000:00487d7a], matchType=14, race =0, level+=0
17:32:36.46   FEAppListener - Processing win/loss notification
17:32:36.46   RendezvousNotifier - received report simulation stats notification, sending simulation stats to lobby.
17:32:36.46   PostStoredStats: matchID = [00000000:0ff91c40] is unknown
17:32:36.76   GetUserStats requested stats for PIDs ( 4750714 ) (best:0, full:1)
17:32:46.01   Activating screen: ViewStats
17:32:48.46   Activating screen: postgame_stats_skirm
17:32:50.48   Activating screen: SaveReplayPopup
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:00.76   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:33:03.01   Transport - median kBPS [hi/cur] sent = 1.7/0.0, recvd = 2.1/0.0, #p/sec[s/r] = 4.9/5.0, max unsent 0, version err 0, merge 4
17:33:18.67   Activating screen: AppRestartingForm
17:33:18.67   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
17:33:18.67   Widget attempting to remove a non-existance listener. Widget:bkg_COH
17:33:18.67   Widget attempting to remove a non-existance listener. Widget:bkg_CXP1
17:33:18.67   Widget attempting to remove a non-existance listener. Widget:bkg_CXP2
17:33:18.67   Widget attempting to remove a non-existance listener. Widget:bkg_COHCXP1
17:33:18.67   Widget attempting to remove a non-existance listener. Widget:bkg_ToV
17:33:18.72   APP -- Game Stop
17:33:18.72   GameApp::TransitionGameStop
17:33:18.72   GameApp::TransitionQuitCommon
17:33:18.72   PerformanceRecorder: Updating profile history
17:33:18.72   GetMaxFrameTimeFromProfile: players=4 expected FPS=42.763996, bars=5, max avg=0.020, sd=0.004, 5 samples =  0.03 0.02 0.02 0.02 0.02
17:33:18.72   PerformanceRecorder::EndRecording - game size=4, max average=0.026685, worst frame=0.023384
17:33:18.72    Recording: No [4 players]
17:33:18.72    Total render, sim time: 3.67, 3.94 sec
17:33:18.72    Total render, sim frames: 213, 0
17:33:18.72    Max/Avg: 0.03, 0.02 sec (fps=37.47, 53.98) (108 samples)
17:33:18.72    Bars: 5
17:33:18.72   Profile Max Performance[4 players] =  0.03 0.02 0.02 0.02 0.02
17:33:18.72   Profile Avg Performance[4 players] =  0.02 0.02 0.02 0.02 0.02
17:33:18.72   GameObj::ShutdownGameObj
17:33:18.72   GAME -- Ending mission (Connection Lost - Kicked Out)
17:33:18.72   GameObjLoader 10a32ab0 - Unload
17:33:18.72   GameObjLoader 10a32ab0 - resetting counters
17:33:18.74   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
17:33:18.74   MOD -- Game Over at frame 17196
17:33:18.81   GameObjLoader 10a32950 - Unload
17:33:18.81   GameObjLoader 10a32950 - resetting counters
17:33:18.84   SOUND -- Shutting down ...
17:33:18.90   SOUND -- Shutdown completed!
17:33:18.90   MessageInternal::DestroyChannel: Destroyed channel 474d4343
17:33:18.90   MessageInternal::DestroyChannel: Destroyed channel 53594e43
17:33:18.91   GAME -- Beginning FE
17:33:18.91   Sent message game CompanyOfHeroes allowtraffic
17:33:18.91   UIFrontEnd - Loading Front End
17:33:18.91   THREAD: Hyper-Threading Technology Processors are not detected.
17:33:18.95   SOUND -- Initializing ...
17:33:18.97   SOUND -- Initialization completed!
17:33:18.97   UIFrontEnd - Initializing Forms
17:33:19.89   CampaignFilter::BindFilterSpecificWidgets()
17:33:19.92   Turning off auto-selection of profiles, we already have a profile selected.
17:33:19.92   MOD -- Mounting Eastern_Front_DLC1, 1.0
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC1\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC1\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC1\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC1\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC1\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC1\Movies\'
17:33:19.92   MOD -- Mounting Eastern_Front_DLC2, 1.0
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC2\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC2\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC2\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC2\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC2\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC2\Movies\'
17:33:19.92   MOD -- Mounting Eastern_Front_DLC3, 1.0
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC3\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC3\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC3\DataArtHigh\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'DLC3\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC3\DataSoundEnglish\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\DLC3\Movies\'
17:33:19.92   MOD -- Mounting Eastern_Front_OPS, 1.0
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\Ops\Data\'
17:33:19.92   MOD -- Mounting Eastern_Front, 1.0
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'RelicOnline\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\RelicOnline\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'CoH\Engine\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\Engine\Data\'
17:33:19.92   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\WW2\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'CoH\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataArtHigh\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\DataArtHigh\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\DataSoundHigh\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\DataSound\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\DataSound\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'Eastern_Front\Locale\English\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\Locale\English\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\English\Data\'
17:33:19.93   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Engine\Locale\English\Data\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'CoH\DataSoundEnglish\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\DataSoundEnglish\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'CoH\Locale\English\Data\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\CoH\Locale\English\Data\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\DataAttrib\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Eastern_Front\Movies\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\Engine\Movies\'
17:33:19.96   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\James\Documents\My Games\Company of Heroes\WW2\Movies\'
17:33:19.97   Activating screen: AppRestartingForm
17:33:19.97   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
17:33:19.97   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
17:33:20.94   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
17:33:20.95   CRC & Version Info : 0000025a:c1dd11bc:ec3c9e0d eastern_front:602:factionfix.dll 1
17:33:20.95   Activating screen: FEMovie
17:33:20.95   Activating screen: OnlineWidget
17:33:20.95   Activating screen: FE_mm_01
17:33:20.97   SetupProductLoadingArt - choosing bgArt = 4 (gold=1)
17:33:20.98   GAME -- Setting campaign state to 'coh'
17:33:20.98   GAME -- Closing state 'coh'
17:33:20.98   GAME -- Setting campaign state to 'cxp2'
17:33:20.98   GAME -- Closing state 'cxp2'
17:33:20.98   GAME -- Setting campaign state to 'cxp1'
17:33:20.98   GAME -- Closing state 'cxp1'
17:33:20.98   GAME -- Setting campaign state to 'dlc1'
17:33:20.98   GAME -- Closing state 'dlc1'
17:33:20.98   GAME -- Setting campaign state to 'dlc2'
17:33:20.98   GAME -- Closing state 'dlc2'
17:33:20.99   GAME -- Setting campaign state to 'dlc3'
17:33:20.99   GAME -- Closing state 'dlc3'
17:33:31.08   Activating screen: QuitPromptDialog
17:33:31.81   GameApp::Quit( exitValue = 0 )
17:33:31.83   GameApp::ShutFrontEnd
17:33:31.84   GAME -- Ending FE
17:33:31.84   UIFrontEnd - Unloading Front End
17:33:31.85   SOUND -- Shutting down ...
17:33:31.91   SOUND -- Shutdown completed!
17:33:31.94   ~FEAppListener - Flushing queued online notifications
17:33:31.95   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
17:33:32.46   DLLDriverLinker -- 2 DLL drivers found.
17:33:32.46   ActionDatabase : [2572] global actions, [133] dummy actions.
17:33:32.46   NetworkManager::Destroy - destroying network manager
17:33:32.46   RendezvousManager::Shutdown - deleting instance
17:33:32.46   RendezvousManager - terminating all server calls in progress
17:33:32.46   CallManager - terminating all server calls in progress (0 in progress)
17:33:32.46   RendezvousManager - destroying chat handler
17:33:32.46   RendezvousManager::DestroyNATTraversalClient - NAT traversal no longer available.
17:33:32.46   ~RendezvousManager - disconnecting from server
17:33:32.74   MessageInternal::DestroyChannel: Destroyed channel 51434b4d
17:33:32.74   MessageInternal::DestroyChannel: Destroyed channel 50525459
17:33:32.74   PartyInternal: Fade to black
17:33:32.74   AutomatchInternal: Fade to black
17:33:32.74   PeerRemoveAll - flushing local session peer data
17:33:32.74   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
17:33:32.74   MessageInternal::DestroyChannel: Destroyed channel 47535450
17:33:32.74   Session - Requested Disconnect for connectionID 1
17:33:32.74   SessionManager::UnregisterSession - Unregistering session 03b2f740
17:33:32.74   SessionManager::~SessionManager - uninitializing
17:33:32.75   Net::ThreadFunction - Returning from the network thread function...
17:33:32.76   Transport::~Transport - shutting down transport
17:33:32.76   Transport::~Connection - closing WINaddr:255.255.255.255:6112;,WINaddr:255.255.255.255:6112;
17:33:32.76   Transport::~Connection - closing WINaddr:192.168.0.2:6112;,WINaddr:192.168.0.2:6112;
17:33:32.76   Transport::~Connection - closing WINaddr:124.150.69.7:6112;,WINaddr:124.150.69.7:6112;
17:33:32.76   Wrote 599 bytes to network datastore login_cache.bin
17:33:32.77   Wrote 132668 bytes to network datastore global_cache.bin
17:33:32.79   Wrote 44938 bytes to network datastore ping_cache.bin
17:33:32.79   Datastore -- uninitialize complete
17:33:32.79   QuazalInitializer - static uninit of Quazal library
17:33:32.79   QuazalInitializer - unhooking memory 0 currently allocated, 76644 allocations, total of 16345156 bytes
