-- Log file for all dbTracef messages --

14:32:27.58   NetworkManager::Create - creating network manager
14:32:27.60   Read 518 bytes from network datastore login_cache.bin
14:32:27.62   Read 112470 bytes from network datastore global_cache.bin
14:32:27.69   Read 41590 bytes from network datastore ping_cache.bin
14:32:27.74   QuazalInitializer - static initializing Quazal library
14:32:27.76   PingCache - populating cache with 1660 pings
14:32:27.77   RemoteDLManager - FATAL: failed to spawn DownloadManager process
14:32:27.77   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
14:32:27.79   WinTransport - CreateSocket exclusive broadcast socket was available.
14:32:27.79   WinTransport - CreateSocket listening for broadcasts on default port
14:32:27.82   WinTransport - Host Name: Nick-PC, aliases: , type=AF_INET, len=4
14:32:27.82   WinTransport - Host IP Address #0: 192.168.1.106
14:32:27.82   WinTransport - Interface #0: ip:192.168.1.106, broadcast:192.168.1.106, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
14:32:27.82   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
14:32:27.82   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:32:27.82   WinTransport - Quazal address string = udp:/address=192.168.1.106;port=6112
14:32:27.82   SessionManager - Peer Header Size = 16 bytes
14:32:27.82   SessionManager - Game Data overhead = 7 bytes
14:32:27.82   SessionManager - Proxy overhead = 7 bytes
14:32:27.82   Net::ThreadFunction - Entering network thread function...
14:32:27.83   MessageInternal::CreateChannel: Created channel 47535450
14:32:27.83   Session::Initialize - info, initializing session object, using threads.
14:32:27.83   SessionManager::RegisterSession - Registering new session 073cf6f0
14:32:27.83   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:32:27.83   AutomatchInternal: Instantiating
14:32:27.83   PartyInternal: Instantiating
14:32:27.83   MessageInternal::CreateChannel: Created channel 50525459
14:32:27.83   MessageInternal::CreateChannel: Created channel 51434b4d
14:32:27.96   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:32:27.96   MessageInternal::CreateChannel: Created channel 534d5347
14:32:27.96   MessageInternal::CreateChannel: Created channel 474d4343
14:32:27.96   MessageInternal::CreateChannel: Created channel 474f424a
14:32:27.96   MessageInternal::CreateChannel: Created channel 4d4f444d
14:32:27.96   MessageInternal::CreateChannel: Created channel 53594e43
14:32:27.96   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:32:27.96   MessageInternal::DestroyChannel: Destroyed channel 474d4343
14:32:27.96   MessageInternal::DestroyChannel: Destroyed channel 474f424a
14:32:27.96   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
14:32:27.96   MessageInternal::DestroyChannel: Destroyed channel 53594e43
14:32:27.97   GAME -- Available memory: 8191MB Physical RAM, 8189MB Pagefile, 4095 Virtual Address Space
14:32:28.21   Transport - Largest received is now 19
14:32:28.21   Transport::OpenInternal request to WINaddr:192.168.1.106:6112;
14:32:28.36   Transport::OpenInternal request to WINaddr:192.168.1.100:6112;
14:32:28.36   Transport - Largest sent is now 30
14:32:28.47   Transport - Largest received is now 30
14:32:30.38   DLLDriverLinker -- Adding driver 'spDx10.dll'.
14:32:30.56   DLLDriverLinker -- Adding driver 'spDx9.dll'.
14:32:30.56   DLLDriverLinker -- 2 DLL drivers found.
14:32:30.61   SPDx10 -- Adapter [NVIDIA GeForce GTX 470]: 1248MB dedicated video memory, 0MB dedicated system memory and 2815MB shared system memory.
14:32:34.53   DLLDriverLinker -- 2 DLL drivers found.
14:32:34.65   SPOOGE - Driver[DirectX10 Rendering Device] version[4,36]
14:32:34.65   GAME -- Resolution set to 1680x1050 (fullscreen).
14:32:34.68   SPDx10 -- Adapter Description = NVIDIA GeForce GTX 470
14:32:34.68   SPDx10 -- Driver Vendor = 0x000010de  Device = 0x000006cd  SubSys = 0x14703842  Rev = 0x000000a3
14:32:34.68   SPDx10 -- Driver Version  Product = 0x0008  Version = 0x0011  SubVersion = 0x00  Build = 266.58
14:32:34.68   SPDx10 -- Driver LUID = 0x00000000-0x00007ac5
14:32:34.68   SPDx10 -- 1248MB dedicated video memory, 0MB dedicated system memory and 2815MB shared system memory available.
14:32:34.70   ShaderDatabase: using shader profile [ps40]
14:32:35.51   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:32:35.70   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:32:35.74   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
14:32:36.73   GameObjLoader 0aafac90 - resetting counters
14:32:36.73   GameObjLoader 0aafac90 - Created loader
14:32:36.73   GameObjLoader 0aafadf0 - resetting counters
14:32:36.73   GameObjLoader 0aafadf0 - Created loader
14:32:37.71   GAME -- Beginning FE
14:32:37.71   UIFrontEnd - Loading Front End
14:32:37.71   THREAD: Hyper-Threading Technology Processors are not detected.
14:32:38.16   SOUND -- Initializing ...
14:32:38.33   INNIMapDCA Key not found: sp_speechducker::time
14:32:38.73   SOUND -- Initialization completed!
14:32:38.73   UIFrontEnd - Initializing Forms
14:32:40.29   CampaignFilter::BindFilterSpecificWidgets()
14:32:40.35   Activating screen: AppLoadingForm
14:32:40.35   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:32:42.17   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
14:32:42.17   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
14:32:42.17   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
14:32:42.20   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
14:32:42.20   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
14:32:42.21   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
14:32:42.71   GAME -- Using player profile NICK-PC
14:32:43.41   Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
14:32:43.67   Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
14:32:46.57   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
14:32:46.57   RendezvousManager: CreateSession - starting profile=Guest login
14:32:47.69   RendezvousManager: Login complete and successfull
14:32:47.69   RendezvousManager initialized
14:32:47.81   Current server English:live version is 601.0, client is 601.0
14:32:47.81   OnConnect: successful connection established, enabling reconnect
14:32:47.81   OnConnect: this wasnt a reconnect, no need for autologin
14:32:47.81   Logging in Corpsgrindr on controller:0
14:32:48.20   Login completed: ACCOUNT_VALIDATED
14:32:48.20   Found 1 profiles for account Corpsgrindr
14:32:48.20   Found profile: Corpsgrindr
14:32:48.20   installed_products = ( DLC1 DLC2 DLC3 )
14:32:48.20   OnLogin: no previous login, auto selecting profile not required
14:32:48.20   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:32:48.20   CRC & Version Info : 00000259:59baf518:48d2c5df eastern_front:601:factionfix.dll 1
14:32:48.25   Activating screen: FEMovie
14:32:48.25   Activating screen: OnlineWidget
14:32:48.25   Activating screen: RelicOnlineProfileSelect
14:32:48.39   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:32:48.39   Activating screen: RelicOnlineWait
14:32:48.39   RendezvousManager - destroying chat handler
14:32:48.39   RendezvousManager: CreateSession - starting logout profile = 100:Guest
14:32:48.57   RendezvousManager: Logout complete
14:32:48.57   RendezvousManager - terminating all server calls in progress
14:32:48.57   CallManager - terminating all server calls in progress (1 in progress)
14:32:48.57   RendezvousManager: OnCredentialsEvent - starting profile login
14:32:49.55   RendezvousManager: Login complete and successfull
14:32:49.55   RendezvousManager - creating chat handler
14:32:49.56   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
14:32:49.68   QuazalSelectProfileAsync - Got UserID
14:32:49.81   Transport - Largest received is now 41
14:32:49.84   GetUserStats requested stats for PIDs ( 2568921 ) (best:0, full:1)
14:32:49.95   SelectProfileAsync - RegisterLocalURLs public [udp:/address=71.59.188.62;port=6112;PID=2568921;RVCID=41765655], private [udp:/address=192.168.1.106;port=6112;PID=2568921]
14:32:50.06   QuazalSelectProfileAsync - Got Full Stats
14:32:50.20   GetAutomatchMaps: Got [32] maps
14:32:50.22   PopulateArmyListBox - skipping race 2
14:32:50.22   PopulateArmyListBox - skipping race 0
14:32:50.22   PopulateArmyListBox - skipping race 1
14:32:50.22   PopulateArmyListBox - skipping race 3
14:32:50.22   AutoMatchForm::OnArmySelectionChanged - sending request info
14:32:50.22   GetMaxFrameTimeFromProfile: players=2 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples =
14:32:50.22   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
14:32:50.22   QuazalSelectProfileAsync - Got Automatch maps
14:32:50.92   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
14:32:51.08   Profile [00000000:002732d9] selected on controller#0
14:32:51.08   Activating screen: FEMovie
14:32:51.08   Activating screen: OnlineWidget
14:32:51.08   Activating screen: FE_mm_01
14:32:51.08   Activating screen: RelicOnlineWait
14:32:51.08   GAME -- Setting campaign state to 'dlc1'
14:32:51.08   GAME -- Closing state 'dlc1'
14:32:51.09   GAME -- Setting campaign state to 'dlc2'
14:32:51.09   GAME -- Closing state 'dlc2'
14:32:51.10   GAME -- Setting campaign state to 'dlc3'
14:32:51.10   GAME -- Closing state 'dlc3'
14:32:55.67   Activating screen: MessageBoxPopup
14:32:55.67   Created Matchinfo
14:32:55.67   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
14:32:55.67   starting online hosting
14:32:55.68   OnlineHostAsync: initiating CallCreateMatch
14:32:55.81   OnlineHostAsync: created gid=180539325
14:32:55.82   Transport - Largest sent is now 78
14:32:55.89   RendezvousNotifier - Received Participate ParticipationEvent.
14:32:55.93   Transport - Largest received is now 103
14:32:56.07   OnlineHostAsync - RegisterLocalURLs public [udp:/address=71.59.188.62;port=6112;PID=2568921;RVCID=41765655], private [udp:/address=192.168.1.106;port=6112;PID=2568921]
14:32:56.21   OnlineHostAsync: initiating UpdateSessionURL [gid=180539325, url=udp:/address=71.59.188.62;port=6112;PID=2568921;RVCID=41765655]
14:32:56.34   OnJoinAdvertisementSuccess - joined online match, server leave notification required
14:32:56.34   starting local hosting
14:32:56.34   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:32:56.34   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.1.106:6112;
14:32:56.34   Transport::OpenInternal request to WINaddr:192.168.1.106:6112;
14:32:56.34   Session::Host sid = AC2CFBD, hostURL = , local addresses = WINaddr:192.168.1.106:6112;
14:32:56.34   ValidateCustomData: called with 475 bytes of custom data
14:32:56.34   Host accepted Peer 1 into the match at address list=WINaddr:192.168.1.106:6112;, routes=WINaddr:192.168.1.106:6112;
14:32:56.34   AdvertisementInternal::Process - EVENT_NEWPEER
14:32:56.34   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
14:32:56.35   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
14:32:56.35   hosting - Session is connected
14:32:56.35   Net::Session::SetVisible - session is set to INVISIBLE.
14:32:56.35   hosting completed successfully
14:32:56.35   HostAsync - completed with HostResult = 0
14:32:56.35   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
14:32:56.35   Activating screen: OnlineGameSetup
14:32:56.35   MessageInternal::CreateChannel: Created channel 534d5347
14:32:56.35   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:32:56.35   MatchInternal::SetMatchType - new type 14 - updating server
14:32:56.37   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:32:56.37   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
14:32:56.38   SetVisible called while !IsConnected
14:32:56.38   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:32:56.38   Activating screen: RelicOnlineChat
14:32:56.38   Activating screen: RelicOnlineNewsScreen
14:32:56.43   Activating screen: RelicOnlineStatsScreen
14:32:56.43   Activating screen: Achievements
14:32:56.43   GAME -- Setting campaign state to 'dlc1'
14:32:56.43   GAME -- Closing state 'dlc1'
14:32:56.44   GAME -- Setting campaign state to 'dlc2'
14:32:56.44   GAME -- Closing state 'dlc2'
14:32:56.44   GAME -- Setting campaign state to 'dlc3'
14:32:56.44   GAME -- Closing state 'dlc3'
14:32:56.44   GAME -- Setting campaign state to 'dlc1'
14:32:56.44   GAME -- Closing state 'dlc1'
14:32:56.45   Activating screen: GameHistory
14:32:56.45   GAME -- Setting campaign state to 'dlc1'
14:32:56.54   GAME -- Closing state 'dlc1'
14:32:56.55   GAME -- Setting campaign state to 'dlc2'
14:32:56.55   GAME -- Closing state 'dlc2'
14:32:56.56   GAME -- Setting campaign state to 'dlc3'
14:32:56.56   GAME -- Closing state 'dlc3'
14:32:56.57   Activating screen: OnlineGameSetup
14:32:56.57   Activating screen: RelicOnlineTabs
14:32:56.57   AutomatchInternal::OnHostComplete - Completed Host with success=1
14:32:56.57   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
14:32:56.57   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
14:32:56.58   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:32:56.58   Net::Session::SetVisible - session is set to INVISIBLE.
14:32:56.59   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:32:56.63   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:32:56.65   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:32:56.65   Sending encoded message 1116 bytes, to UserID [00000000:00274add]
14:32:56.73   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:32:58.10   GetUserStats requested stats for PIDs ( 2575069 ) (best:1, full:0)
14:32:58.10   QueryMatches: Got [53] maps, [152] ids, [17] advertisements, startID [1]
14:32:58.10   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 135 matches
14:33:01.68   QueryMatches: Got [56] maps, [152] ids, [17] advertisements, startID [180537001]
14:33:01.68   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 118 matches
14:33:06.76   QueryMatches: Got [56] maps, [151] ids, [17] advertisements, startID [180538209]
14:33:06.76   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 100 matches
14:33:06.90   Transport - Largest sent is now 79
14:33:10.54   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:33:10.60   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:33:11.57   QueryMatches: Got [55] maps, [153] ids, [17] advertisements, startID [180538619]
14:33:11.57   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 85 matches
14:33:11.59   RNT_ReportPing: user 503096:Warmachine519 CANNOT connect pingMS=65535
14:33:12.95   Activating screen: RaceSelectionPopup
14:33:13.97   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:13.97   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:13.97   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:13.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:14.00   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:14.02   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:15.25   RNT_ReportPing: user 3431211:Routs666 CANNOT connect pingMS=65535
14:33:16.53   QueryMatches: Got [55] maps, [155] ids, [17] advertisements, startID [180538788]
14:33:16.53   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 70 matches
14:33:19.07   RNT_ReportPing: user 2825139:RoyalYm CANNOT connect pingMS=65535
14:33:21.54   QueryMatches: Got [59] maps, [159] ids, [17] advertisements, startID [180538946]
14:33:21.54   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 60 matches
14:33:22.76   Activating screen: RaceSelectionPopup
14:33:23.71   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:23.71   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:23.71   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:23.71   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:23.73   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:23.75   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:23.90   RNT_ReportPing: user 1206784:MNA75 CANNOT connect pingMS=65535
14:33:24.22   RNT_ReportPing: user 3157925:Darkoveride CANNOT connect pingMS=65535
14:33:24.50   Activating screen: RaceSelectionPopup
14:33:25.22   RNT_ReportPing: user 2971247:DeathBeforeDishonour CANNOT connect pingMS=65535
14:33:25.35   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:25.35   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:25.35   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:25.35   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:25.37   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:25.39   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:25.76   RNT_ReportPing: user 3538072:eskadrill CANNOT connect pingMS=65535
14:33:26.41   Activating screen: DynamicPopupMenu
14:33:26.42   QueryMatches: Got [58] maps, [159] ids, [17] advertisements, startID [180539071]
14:33:26.43   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 46 matches
14:33:26.48   RNT_ReportPing: user 1526101:BallsMcGeeJunior CANNOT connect pingMS=65535
14:33:26.95   RNT_ReportPing: user 3504159:nichda CANNOT connect pingMS=65535
14:33:27.36   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:27.38   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:27.72   RNT_ReportPing: user 3193181:SODOM2010 CANNOT connect pingMS=65535
14:33:27.94   Activating screen: DynamicPopupMenu
14:33:28.01   Transport - median kBPS [hi/cur] sent = 1.2/0.9, recvd = 0.5/0.2, #p/sec[s/r] = 3.2/1.4, max unsent 0, version err 0, merge 1
14:33:29.38   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:29.38   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:29.38   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:29.38   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:29.40   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:29.42   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:29.79   RNT_ReportPing: user 733413:SilverSixteen CANNOT connect pingMS=65535
14:33:30.39   Activating screen: DynamicPopupMenu
14:33:31.23   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:31.23   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:31.23   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:31.24   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:31.26   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:31.27   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:31.42   QueryMatches: Got [55] maps, [157] ids, [17] advertisements, startID [180539183]
14:33:31.43   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
14:33:32.09   RNT_ReportPing: user 3630022:bigginz CANNOT connect pingMS=65535
14:33:32.97   RendezvousNotifier - Received JoinRequest Event 3 (pid=2575069, p1=0, p2=0, str=).
14:33:33.02   ReplyToInvitationAsync: Call started.
14:33:33.06   ExtendInvitationAsync: Call started.
14:33:34.03   Activating screen: DynamicPopupMenu
14:33:35.08   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:35.08   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:35.09   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:35.09   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:35.11   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:35.12   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:35.76   Activating screen: RaceSelectionPopup
14:33:36.43   QueryMatches: Got [54] maps, [156] ids, [17] advertisements, startID [180539270]
14:33:36.44   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 16 matches
14:33:36.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:37.00   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:37.38   Activating screen: DynamicPopupMenu
14:33:37.98   RendezvousNotifier - Received Participate ParticipationEvent.
14:33:39.03   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:39.03   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:39.03   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:39.03   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:39.05   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:39.07   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:39.09   RNT_ReportPing: user 3395115:FSTboing CANNOT connect pingMS=65535
14:33:39.60   Activating screen: RaceSelectionPopup
14:33:39.94   Transport - Largest received is now 389
14:33:39.95   Session - rcv'd Join request - address WINaddr:192.168.1.100:6112; not currently in use
14:33:39.95   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:33:39.95   Session::CheckAddress - initiating address check for WINaddr:192.168.1.100:6112;
14:33:39.95   Allocated route ID=0 for PeerID 2 at WINaddr:192.168.1.100:6112;
14:33:39.95   Transport::OpenInternal request to WINaddr:192.168.1.100:6112;
14:33:39.95   Session::CheckAddressSuccess - peer located at address WINaddr:192.168.1.100:6112;
14:33:39.95   Peer::AcceptRoute - accepted route 5<>11 WINaddr:192.168.1.100:6112; for peerID:2
14:33:39.95   Session - generating NewPeerAcceptedEventEx for peer 2@WINaddr:192.168.1.100:6112;
14:33:39.95   ValidateCustomData: called with 522 bytes of custom data
14:33:39.95   Host accepted Peer 2 into the match at address list=WINaddr:192.168.1.100:6112;, routes=WINaddr:192.168.1.100:6112;
14:33:39.95   AdvertisementInternal::Process - EVENT_NEWPEER
14:33:39.95   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:39.95   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:39.95   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:39.95   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:39.95   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:39.96   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:39.97   Transport - Largest sent is now 1272
14:33:40.00   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.02   Station [0:2] acknowledged matchinfo change #59
14:33:40.03   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.05   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:40.05   Station [0:2] acknowledged matchinfo change #61
14:33:40.06   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:40.06   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:40.06   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:40.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.11   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.13   Station [0:2] acknowledged matchinfo change #63
14:33:40.14   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.21   Station [0:2] acknowledged matchinfo change #65
14:33:40.21   Station [0:2] acknowledged matchinfo change #68
14:33:40.22   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:40.80   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:40.80   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:40.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.90   Station [0:2] acknowledged matchinfo change #69
14:33:40.90   Station [0:2] acknowledged matchinfo change #71
14:33:40.92   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:40.98   Station [0:2] acknowledged matchinfo change #74
14:33:40.99   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:41.03   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:41.43   QueryMatches: Got [53] maps, [156] ids, [17] advertisements, startID [180539359]
14:33:41.43   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 4 matches
14:33:42.71   Activating screen: Achievements
14:33:42.71   Activating screen: RelicOnlineTabs
14:33:44.14   RNT_ReportPing: user 3644433:ROMMEL12365 CANNOT connect pingMS=65535
14:33:44.39   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:44.39   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:44.39   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:44.39   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.43   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.45   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:44.45   Station [0:2] acknowledged matchinfo change #75
14:33:44.47   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.55   Station [0:2] acknowledged matchinfo change #77
14:33:44.56   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.58   Station [0:2] acknowledged matchinfo change #80
14:33:44.58   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.86   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:44.86   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:44.86   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:44.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.90   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.92   Station [0:2] acknowledged matchinfo change #81
14:33:44.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.96   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:44.98   Station [0:2] acknowledged matchinfo change #83
14:33:44.99   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:45.01   RNT_ReportPing: user 3643567:13dead CANNOT connect pingMS=65535
14:33:45.01   Station [0:2] acknowledged matchinfo change #86
14:33:45.02   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:45.04   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:46.39   QueryMatches: Got [56] maps, [155] ids, [17] advertisements, startID [180539428]
14:33:47.38   Activating screen: MessageBoxPopup
14:33:47.38   Session is marking PeerID 2 for death
14:33:47.38   Session is marking the local peer PeerID 1 for death
14:33:47.38   OnlineLeaveAdvertisementAsync: Call to LeaveMatch id = 180539325, cancel = 1, badPids = 0, matchState = 0
14:33:47.38   Session::GetEvent DestroyPeer success Peer 2 removed
14:33:47.38   Session::DestroyPeer - deallocating peer 2
14:33:47.38   Session - Requested Disconnect for connectionID 4
14:33:47.38   Deallocated route ID=5 for PeerID 2 at WINaddr:192.168.1.100:6112;
14:33:47.38   Session - Requested Disconnect for connectionID 5
14:33:47.38   Closed route 5<>11 for PeerID 2 at WINaddr:192.168.1.100:6112;
14:33:47.38   AdvertisementInternal::Process - EVENT_DESTROYPEER
14:33:47.38   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:47.38   SetVisible called while !IsConnected
14:33:47.39   Session::GetEvent DestroyPeer success Peer 1 removed
14:33:47.39   Session::DestroyPeer - deallocating peer 1
14:33:47.39   Session - Requested Disconnect for connectionID 2
14:33:47.39   Deallocated route ID=3 for PeerID 1 at WINaddr:192.168.1.106:6112;
14:33:47.39   Session - Requested Disconnect for connectionID 3
14:33:47.39   Closed route 3<>3 for PeerID 1 at WINaddr:192.168.1.106:6112;
14:33:47.39   AdvertisementInternal::Process - EVENT_DESTROYPEER
14:33:47.39   LeaveMatchInternal - no action taken, needLeave=0, service=0
14:33:47.39   Destroyed Matchinfo
14:33:47.43   Session - Expected ConnectionID 5 doesn't exist, will close remote.
14:33:47.75   RNT_CancelParticipation: user 2575069:Brandonn2008 left the match
14:33:47.79   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:33:47.79   PeerRemoveAll - flushing local session peer data
14:33:47.79   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
14:33:47.82   Activating screen: FEMovie
14:33:47.82   Activating screen: OnlineWidget
14:33:47.82   Activating screen: FE_mm_01
14:33:47.85   GAME -- Setting campaign state to 'dlc1'
14:33:47.85   GAME -- Closing state 'dlc1'
14:33:47.86   GAME -- Setting campaign state to 'dlc2'
14:33:47.86   GAME -- Closing state 'dlc2'
14:33:47.86   GAME -- Setting campaign state to 'dlc3'
14:33:47.86   GAME -- Closing state 'dlc3'
14:33:47.87   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:33:47.87   GAME -- Setting campaign state to 'dlc1'
14:33:47.87   GAME -- Closing state 'dlc1'
14:33:47.88   GAME -- Setting campaign state to 'dlc2'
14:33:47.88   GAME -- Closing state 'dlc2'
14:33:47.88   GAME -- Setting campaign state to 'dlc3'
14:33:47.88   GAME -- Closing state 'dlc3'
14:33:47.95   RNT_ReportPing: user 3083679:tictus CANNOT connect pingMS=65535
14:33:48.89   Activating screen: MessageBoxPopup
14:33:48.89   Created Matchinfo
14:33:48.89   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
14:33:48.89   starting online hosting
14:33:48.90   OnlineHostAsync: initiating CallCreateMatch
14:33:49.07   OnlineHostAsync: created gid=180539473
14:33:49.17   RendezvousNotifier - Received Participate ParticipationEvent.
14:33:49.33   OnlineHostAsync - RegisterLocalURLs public [udp:/address=71.59.188.62;port=6112;PID=2568921;RVCID=41765655], private [udp:/address=192.168.1.106;port=6112;PID=2568921]
14:33:49.46   OnlineHostAsync: initiating UpdateSessionURL [gid=180539473, url=udp:/address=71.59.188.62;port=6112;PID=2568921;RVCID=41765655]
14:33:49.59   RNT_ReportPing: user 1458028:aVolkoff CANNOT connect pingMS=65535
14:33:49.60   OnJoinAdvertisementSuccess - joined online match, server leave notification required
14:33:49.60   starting local hosting
14:33:49.60   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:33:49.60   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.1.106:6112;
14:33:49.60   Transport::OpenInternal request to WINaddr:192.168.1.106:6112;
14:33:49.60   Session::Host sid = AC2D051, hostURL = , local addresses = WINaddr:192.168.1.106:6112;
14:33:49.60   ValidateCustomData: called with 475 bytes of custom data
14:33:49.60   Host accepted Peer 1 into the match at address list=WINaddr:192.168.1.106:6112;, routes=WINaddr:192.168.1.106:6112;
14:33:49.60   AdvertisementInternal::Process - EVENT_NEWPEER
14:33:49.60   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
14:33:49.61   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
14:33:49.61   hosting - Session is connected
14:33:49.61   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:49.61   hosting completed successfully
14:33:49.61   HostAsync - completed with HostResult = 0
14:33:49.61   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
14:33:49.61   Activating screen: OnlineGameSetup
14:33:49.61   MessageInternal::CreateChannel: Created channel 534d5347
14:33:49.61   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:49.61   MatchInternal::SetMatchType - new type 14 - updating server
14:33:49.62   SetVisible called while !IsConnected
14:33:49.62   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:49.63   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:33:49.63   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
14:33:49.63   Activating screen: RelicOnlineChat
14:33:49.63   Activating screen: RelicOnlineNewsScreen
14:33:49.68   Activating screen: RelicOnlineStatsScreen
14:33:49.68   Activating screen: Achievements
14:33:49.68   GAME -- Setting campaign state to 'dlc1'
14:33:49.68   GAME -- Closing state 'dlc1'
14:33:49.68   GAME -- Setting campaign state to 'dlc2'
14:33:49.68   GAME -- Closing state 'dlc2'
14:33:49.69   GAME -- Setting campaign state to 'dlc3'
14:33:49.69   GAME -- Closing state 'dlc3'
14:33:49.69   GAME -- Setting campaign state to 'dlc1'
14:33:49.69   GAME -- Closing state 'dlc1'
14:33:49.70   Activating screen: GameHistory
14:33:49.70   GAME -- Setting campaign state to 'dlc1'
14:33:49.70   GAME -- Closing state 'dlc1'
14:33:49.70   GAME -- Setting campaign state to 'dlc2'
14:33:49.70   GAME -- Closing state 'dlc2'
14:33:49.71   GAME -- Setting campaign state to 'dlc3'
14:33:49.71   GAME -- Closing state 'dlc3'
14:33:49.71   Activating screen: OnlineGameSetup
14:33:49.71   Activating screen: RelicOnlineTabs
14:33:49.71   AutomatchInternal::OnHostComplete - Completed Host with success=1
14:33:49.71   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
14:33:49.71   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
14:33:49.72   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:49.72   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:49.72   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:49.73   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:49.75   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:49.75   Sending encoded message 1164 bytes, to UserID [00000000:00274add]
14:33:49.77   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:51.21   Activating screen: DynamicPopupMenu
14:33:52.21   Activating screen: DynamicPopupMenu
14:33:53.29   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:53.29   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:53.29   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:53.29   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:53.31   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:53.32   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:53.98   RNT_ReportPing: user 3515747:jaww66 CANNOT connect pingMS=65535
14:33:54.05   Activating screen: RaceSelectionPopup
14:33:54.63   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:54.63   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:54.63   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:54.63   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:54.65   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:54.66   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:55.02   Activating screen: DynamicPopupMenu
14:33:55.94   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:55.94   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:55.94   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:55.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:55.97   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:55.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:56.49   RNT_ReportPing: user 2975230:stli94 CANNOT connect pingMS=65535
14:33:57.79   RNT_ReportPing: user 451546:Averypj CANNOT connect pingMS=65535
14:33:59.28   Activating screen: RaceSelectionPopup
14:33:59.85   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:33:59.85   Net::Session::SetVisible - session is set to INVISIBLE.
14:33:59.85   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:33:59.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:33:59.89   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:33:59.91   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:00.34   Activating screen: RaceSelectionPopup
14:34:00.99   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:00.99   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:01.00   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:01.00   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:01.02   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:01.03   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:01.66   Activating screen: DynamicPopupMenu
14:34:02.30   RNT_ReportPing: user 714674:Sappers397 CANNOT connect pingMS=65535
14:34:02.40   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:02.40   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:02.40   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:02.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:02.42   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:02.44   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:02.83   RendezvousNotifier - Received JoinRequest Event 3 (pid=2575069, p1=0, p2=0, str=).
14:34:02.89   ReplyToInvitationAsync: Call started.
14:34:02.93   ExtendInvitationAsync: Call started.
14:34:03.16   Activating screen: DynamicPopupMenu
14:34:04.17   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:04.17   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:04.17   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:04.17   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:04.19   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:04.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:07.86   RendezvousNotifier - Received Participate ParticipationEvent.
14:34:08.18   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:08.18   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:08.18   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:08.20   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:08.21   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:08.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:09.80   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:09.80   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:09.80   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:09.89   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:09.90   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:09.93   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.07   Session - rcv'd Join request - address WINaddr:192.168.1.100:6112; not currently in use
14:34:10.07   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:34:10.07   Session::CheckAddress - initiating address check for WINaddr:192.168.1.100:6112;
14:34:10.07   Allocated route ID=0 for PeerID 2 at WINaddr:192.168.1.100:6112;
14:34:10.07   Transport::OpenInternal request to WINaddr:192.168.1.100:6112;
14:34:10.07   Session::CheckAddressSuccess - peer located at address WINaddr:192.168.1.100:6112;
14:34:10.07   Peer::AcceptRoute - accepted route 9<>15 WINaddr:192.168.1.100:6112; for peerID:2
14:34:10.07   Session - generating NewPeerAcceptedEventEx for peer 2@WINaddr:192.168.1.100:6112;
14:34:10.07   ValidateCustomData: called with 522 bytes of custom data
14:34:10.07   Host accepted Peer 2 into the match at address list=WINaddr:192.168.1.100:6112;, routes=WINaddr:192.168.1.100:6112;
14:34:10.07   AdvertisementInternal::Process - EVENT_NEWPEER
14:34:10.07   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:10.07   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:10.07   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:10.07   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:10.08   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:10.08   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.09   Transport - Largest sent is now 1277
14:34:10.11   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.12   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:10.15   Station [0:2] acknowledged matchinfo change #61
14:34:10.15   Station [0:2] acknowledged matchinfo change #63
14:34:10.15   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:10.15   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:10.15   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:10.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.19   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.26   Station [0:2] acknowledged matchinfo change #65
14:34:10.26   Station [0:2] acknowledged matchinfo change #67
14:34:10.26   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:10.32   Station [0:2] acknowledged matchinfo change #70
14:34:10.33   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.13   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:11.13   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:11.13   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:11.14   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.15   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:11.18   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.19   Station [0:2] acknowledged matchinfo change #71
14:34:11.20   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.24   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.25   Station [0:2] acknowledged matchinfo change #72
14:34:11.26   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:11.30   Station [0:2] acknowledged matchinfo change #74
14:34:11.30   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:12.28   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:12.28   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:12.28   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:12.30   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:12.32   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:12.33   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:12.33   Station [0:2] acknowledged matchinfo change #75
14:34:12.35   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:12.39   Station [0:2] acknowledged matchinfo change #77
14:34:12.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:12.44   Station [0:2] acknowledged matchinfo change #80
14:34:12.45   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:14.75   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:14.75   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:14.75   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:14.77   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:14.79   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:14.80   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:14.82   Station [0:2] acknowledged matchinfo change #81
14:34:14.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:14.86   Station [0:2] acknowledged matchinfo change #82
14:34:14.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:14.89   Station [0:2] acknowledged matchinfo change #84
14:34:14.90   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:16.82   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:16.82   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:16.82   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:16.84   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:16.86   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:16.87   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:16.91   Station [0:2] acknowledged matchinfo change #85
14:34:16.91   Station [0:2] acknowledged matchinfo change #86
14:34:16.92   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:17.00   Station [0:2] acknowledged matchinfo change #88
14:34:17.00   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:18.61   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:18.61   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:18.61   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:18.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:18.63   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:18.65   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:18.67   Station [0:2] acknowledged matchinfo change #89
14:34:18.69   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:18.71   Station [0:2] acknowledged matchinfo change #90
14:34:18.72   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:18.76   Station [0:2] acknowledged matchinfo change #92
14:34:18.78   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:20.60   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:20.60   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:20.60   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:20.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:20.62   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:20.64   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:20.71   Station [0:2] acknowledged matchinfo change #93
14:34:20.71   Station [0:2] acknowledged matchinfo change #94
14:34:20.72   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:20.79   Station [0:2] acknowledged matchinfo change #96
14:34:20.81   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:23.25   RNT_ReportPing: user 3644986:fecka87 CANNOT connect pingMS=65535
14:34:26.03   Activating screen: RaceSelectionPopup
14:34:26.88   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:26.88   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:26.88   GetMaxFrameTimeFromProfile: players=8 expected FPS=14.588384, bars=4, max avg=0.060, sd=0.009, 5 samples =  0.06 0.05 0.06 0.06 0.08
14:34:26.89   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:26.91   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:26.92   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:26.94   Station [0:2] acknowledged matchinfo change #97
14:34:26.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:26.97   Station [0:2] acknowledged matchinfo change #99
14:34:26.99   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:27.03   Station [0:2] acknowledged matchinfo change #102
14:34:27.04   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:29.01   Transport - median kBPS [hi/cur] sent = 3.1/0.5, recvd = 1.1/0.2, #p/sec[s/r] = 7.3/4.6, max unsent 0, version err 0, merge 6
14:34:29.29   RNT_ReportPing: user 3123083:rac2807 CANNOT connect pingMS=65535
14:34:30.05   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:30.10   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:30.11   Station [0:2] acknowledged matchinfo change #103
14:34:30.12   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:31.12   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:34:31.12   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:31.12   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:31.12   MatchInternal::SetMatchState - state 1 - updating server
14:34:31.12   Net::Session::SetVisible - session is set to INVISIBLE.
14:34:31.12   MatchSetup: Sending start game message!
14:34:31.12   GameSetupForm - Starting game
14:34:31.12   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:34:31.13   PopulateGameInfo - random seed:[1306013671], guid:[{bc833b16-dfad-4151-9f38-b481dcb99502}], sync level:[0]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.13   Error loading [DATA:levelingCurve.lua]
14:34:31.14   Error loading [DATA:levelingCurve.lua]
14:34:31.14   Error loading [DATA:levelingCurve.lua]
14:34:31.14   MOD - Setting player (0) race to: allies_soviets
14:34:31.14   MOD - Setting player (0) race to: 2
14:34:31.14   MOD - Setting player (1) race to: allies_soviets
14:34:31.14   MOD - Setting player (1) race to: 2
14:34:31.14   MOD - Setting player (2) race to: allies_commonwealth
14:34:31.14   MOD - Setting player (2) race to: 0
14:34:31.14   MOD - Setting player (3) race to: allies
14:34:31.14   MOD - Setting player (3) race to: 1
14:34:31.14   MOD - Setting player (4) race to: axis_panzer_elite
14:34:31.14   MOD - Setting player (4) race to: 4
14:34:31.14   MOD - Setting player (5) race to: axis
14:34:31.14   MOD - Setting player (5) race to: 3
14:34:31.14   MOD - Setting player (6) race to: axis_panzer_elite
14:34:31.14   MOD - Setting player (6) race to: 4
14:34:31.14   MOD - Setting player (7) race to: axis
14:34:31.14   MOD - Setting player (7) race to: 3
14:34:31.15   OnlineUpdateStateAsync: initiating state change, id = 180539473, state=2
14:34:31.17   APP -- Game Start
14:34:31.17   GAME -- Setting campaign state to 'dlc1'
14:34:31.17   GAME -- Closing state 'dlc1'
14:34:31.17   GAME -- Setting campaign state to 'dlc2'
14:34:31.17   GAME -- Closing state 'dlc2'
14:34:31.18   GAME -- Setting campaign state to 'dlc3'
14:34:31.18   GAME -- Closing state 'dlc3'
14:34:31.18   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:34:31.19   GAME -- Ending FE
14:34:31.19   UIFrontEnd - Unloading Front End
14:34:31.20   SOUND -- Shutting down ...
14:34:31.29   SOUND -- Shutdown completed!
14:34:31.30   MessageInternal::CreateChannel: Created channel 53594e43
14:34:31.30   P2PNetworkSynchronization::RegisterPeer - info, registered local station [1].
14:34:31.30   P2PNetworkSynchronization::RegisterPeer - info, registered remote station [2].
14:34:31.30   MessageInternal::CreateChannel: Created channel 474f424a
14:34:31.30
14:34:31.30   GAME -- *** Beginning mission HENSCHEL AIRFIELD (2 Humans, 6 Computers) ***
14:34:31.30
14:34:31.30   GameObj::StartGameObj - info, network session GUID set to [{bc833b16-dfad-4151-9f38-b481dcb99502}].
14:34:31.30   GAME -- Recording game
14:34:31.35   Activating screen: GameLoadScreen
14:34:31.35   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:34:31.37   Station [0:2] acknowledged matchinfo change #104
14:34:31.37   GameObjController - OnMatchEvent: event type 6
14:34:31.74   OnlineUpdateStateAsync: updated state for gid=180539473
14:34:32.27   THREAD: Hyper-Threading Technology Processors are not detected.
14:34:32.37   SOUND -- Initializing ...
14:34:32.84   SOUND -- Initialization completed!
14:34:32.91   PHYSICS: detected processor(s) capable of handling 6 threads.
14:34:32.96   RNT_ReportPing: user 20426:Miller CANNOT connect pingMS=65535
14:34:32.96   MOD -- Locating MOD for scenario 'DATA:Scenarios\MP\HENSCHEL AIRFIELD'
14:34:32.96   MOD -- Using Mod 'Eastern_Front'
14:34:32.98   Unable to load/parse precache file [DATA:Scenarios\MP\HENSCHEL AIRFIELD_precache.lua]
14:34:33.06   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
14:34:33.06   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
14:34:35.86   RNT_ReportPing: user 586832:holstabre CANNOT connect pingMS=65535
14:34:35.86   RNT_ReportPing: user 3620399:MrWolf1987 CANNOT connect pingMS=65535
14:34:36.68   GameObjLoader - upgrading load_count from 0 to 1294
14:34:37.64   RNT_ReportPing: user 3635284:TAWVladhimir CANNOT connect pingMS=65535
14:34:46.51   GameObjLoader - upgrading load_count from 1294 to 2188
14:34:46.75   PHYSICS -- Created node factory 'HVOK'
14:34:46.75   PHYSICS -- Created node factory 'DMMY'
14:34:50.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:34:50.00              PeerID 2 CONN ack=  0 (180ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.100:6112; (ping=10ms) 100.00%, pending=0, dead=0
14:34:50.00   MessageCounts: inval=0/0, seek=0/0, join=0/1, integ=0/0, seek_reply=0/0, join_reply=0/0, add=2/0, remove=0/0, drop=0/0, data=74/101, voice=0/0, rchk=0/0, nudge=10/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
14:35:13.08   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
14:35:19.84   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:21.76   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:23.56   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:25.66   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:27.70   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:28.00   GameObjLoader - upgrading load_count from 33 to 5261
14:35:29.66   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:30.00   Transport - median kBPS [hi/cur] sent = 3.1/0.2, recvd = 1.1/0.1, #p/sec[s/r] = 7.5/6.7, max unsent 0, version err 0, merge 6
14:35:31.67   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:33.56   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:35.66   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:37.61   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:39.96   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:40.11   GameObjLoader 0aafac90 - resetting counters
14:35:40.11   GameObjLoader 0aafac90 - LOAD_DONE
14:35:40.11   GAME - SessionSetup
14:35:40.11   GAME -- Can't initialize scenario pack more than once.
14:35:40.59   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
14:35:40.59   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
14:35:40.59   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
14:35:46.17   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.17   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.17   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.17   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.18   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.20   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.20   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.21   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.21   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.21   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:46.35   Warning: RenderCompositeStrips AddStripSegment : time == FLT_MAX
14:35:51.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:35:51.00              PeerID 2 CONN ack=  0 ( 50ms~0) unack=  0, retry=  2, highwaterOOS=0 @WINaddr:192.168.1.100:6112; (ping=10ms) 99.11%, pending=0, dead=0
14:35: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=147/67, voice=0/0, rchk=0/0, nudge=5/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
14:35:55.26   GAME - CreateGEWorld in 15150 ms
14:35:55.27   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
14:35:55.64   GAME - SessionSetup finished in 15530 ms
14:35:55.64   RNT_ReportPing: user 3641519:GuizmoFR CANNOT connect pingMS=65535
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:55.64   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:35:56.46   GAME - WaterReflectionManagerSetup
14:35:56.46   GAME - WaterReflectionManagerSetup finished in 0 ms
14:35:57.27   MessageInternal::CreateChannel: Created channel 474d4343
14:35:57.28   Regenerating ImpassMap data...
14:35:57.28       Impass Data was already valid, but regenerating...
14:35:57.59   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_03_edit_load.smf'.  Expensive operation
14:35:57.71   Generating CanBuild Map.  THIS SHOULD ONLY HAPPEN IN WORLDBUILDER!  IF YOU SEE THIS IN GAME, RE-SAVE THE MAP!
14:35:57.71   Regenerating CanBuildMap data...
14:35:57.71   Generating CanShoot Map.
14:35:57.71   Pathfinder::Regenerate()...
14:35:57.87   Generating PathSectorMap...
14:35:58.69   Pathfinder::Regenerate() Done.
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x06bcce68
[Cope FactionFix] - patched @0x70f854c1
[Cope FactionFix] - hooked @0x70f854c2
[Cope FactionFix] - patched @0x70f84a37
[Cope FactionFix] - hooked @0x70f84a37
[Cope FactionFix] - Hook installed
14:35:59.10   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded.
14:36:01.41   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:36:01.41   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:36:01.45   MessageInternal::CreateChannel: Created channel 4d4f444d
14:36:02.30   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:36:02.31   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:36:02.31   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:36:02.31   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:36:02.31   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
14:36:02.73   SPEECHMANAGER -- Loaded in 0.379607 seconds
14:36:03.44   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:36:04.23   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:36:04.39   GameObjLoader - upgrading load_count from 0 to 401
14:36:05.68   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000002, pid 0:2575069, Brandonn2008]" finished loading with checksum [406569999].
14:36:06.14   GameObjLoader 0aafadf0 - resetting counters
14:36:06.14   GameObjLoader 0aafadf0 - LOAD_DONE
14:36:06.84   PreloadResources took 0ms.
14:36:06.90   GAME -- Loading completed (96 seconds)
14:36:06.90   SIM -- Setting SyncErrorChecking level to Low
14:36:06.94   LoadArbitrator::UpdateLoadProgress - info, player "[sid 00000000:00000001, pid 0:2568921, Corpsgrindr]" finished loading with checksum [406569999].
14:36:06.94   Activating screen: GameScreen
14:36:06.94   Activating screen: Decorators_widescreen
14:36:06.94   Activating screen: Taskbar_widescreen
14:36:06.94   Activating screen: SubtitleScreen
14:36:06.94   Activating screen: TextOverlayScreen
14:36:06.99   PerformanceRecorder::StartRecording for game size 8
14:36:06.99   GAME -- Starting mission...
14:36:07.07   We do not have a sync command for peer=2, currentframe=0
14:36:07.16   We do not have a sync command for peer=2, currentframe=0
14:36:07.29   We do not have a sync command for peer=2, currentframe=0
14:36:07.42   We do not have a sync command for peer=2, currentframe=0
14:36:07.42   P2PNetworkSynchronization::GetSyncCommand - warning, no sync command from  station [2], current lag complaint threshold = [2] frames.
14:36:07.54   We do not have a sync command for peer=2, currentframe=0
14:36:07.67   We do not have a sync command for peer=2, currentframe=0
14:36:07.80   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 0) (CmdAI)
14:36:07.80   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 0) (CmdAI)
14:36:07.80   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 0) (CmdAI)
14:36:08.05   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 2) (CmdAI)
14:36:08.05   MOD -- Player CPU - Hard set to AI Type: AI Player (frame 2) (CmdAI)
14:36:08.05   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 2) (CmdAI)
14:36:13.14   Warning: non auto-match upgrade not found in AE, tuning
14:36:14.16   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:36:14.23   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
14:36:16.60   Activating screen: Command_Tree
14:36:18.63   Activating screen: Command_Branch
14:36:23.64   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
14:36:31.01   Transport - median kBPS [hi/cur] sent = 3.1/0.5, recvd = 1.1/0.5, #p/sec[s/r] = 5.4/4.8, max unsent 0, version err 0, merge 5
14:36:45.34   Warning: binding repeat_1(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
14:36:52.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:36:52.01              PeerID 2 CONN ack=  0 (130ms~0) unack=  0, retry=  3, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=10ms) 99.50%, pending=0, dead=0
14:36: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=376/372, voice=0/0, rchk=0/0, nudge=5/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
14:37:26.03   Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
14:37:32.01   Transport - median kBPS [hi/cur] sent = 3.1/0.6, recvd = 1.1/0.6, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 3
14:37:53.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:37:53.01              PeerID 2 CONN ack=  0 (140ms~0) unack=  1, retry=  3, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=10ms) 99.73%, pending=0, dead=0
14:37:53.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/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
14:38:33.01   Transport - median kBPS [hi/cur] sent = 3.1/0.6, recvd = 1.1/0.5, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 3
14:38:54.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:38:54.01              PeerID 2 CONN ack=  0 ( 50ms~0) unack=  1, retry=  4, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=10ms) 99.75%, pending=0, dead=0
14:38: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=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
14:38:58.80   We do not have a sync command for peer=2, currentframe=1376
14:39:34.00   Transport - median kBPS [hi/cur] sent = 3.1/0.7, recvd = 1.1/0.8, #p/sec[s/r] = 8.5/8.4, max unsent 0, version err 0, merge 2
14:39:34.57   Activating screen: Command_Branch
14:39:55.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:39:55.01              PeerID 2 CONN ack=  0 (130ms~0) unack=  1, retry=  6, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=15ms) 99.71%, pending=0, dead=0
14:39: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=490/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
14:40:35.01   Transport - median kBPS [hi/cur] sent = 3.1/0.8, recvd = 1.1/0.7, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 1
14:40:56.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:40:56.01              PeerID 2 CONN ack=  0 ( 50ms~0) unack=  1, retry=  7, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=21ms) 99.73%, pending=0, dead=0
14:40: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=492/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
14:41:08.66   We do not have a sync command for peer=2, currentframe=2421
14:41:36.01   Transport - median kBPS [hi/cur] sent = 3.1/0.9, recvd = 1.1/0.9, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 11
14:41:57.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:41:57.01              PeerID 2 CONN ack=  0 (150ms~0) unack=  1, retry= 10, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=13ms) 99.67%, pending=0, dead=0
14:41:57.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/490, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
14:42:06.88   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:09.73   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:11.62   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:12.38   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:12.73   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:12.73   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:15.81   We do not have a sync command for peer=2, currentframe=2961
14:42:37.01   Transport - median kBPS [hi/cur] sent = 3.1/1.2, recvd = 1.1/0.7, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 6
14:42:37.91   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:39.15   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:42:58.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:42:58.01              PeerID 2 CONN ack=  0 (160ms~0) unack=  1, retry= 11, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=23ms) 99.69%, pending=0, dead=0
14:42:58.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/18, frag=0/0, Errors=0/0
14:42:59.34   Activating screen: Command_Branch
14:43:11.98   We do not have a sync command for peer=2, currentframe=3417
14:43:18.66   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:43:38.01   Transport - median kBPS [hi/cur] sent = 3.1/1.1, recvd = 1.1/0.8, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 3
14:43:54.82   We do not have a sync command for peer=2, currentframe=3765
14:43:58.19   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
14:43:58.19   Muting spew of bind updater.  Fix art and re-test.
14:43:59.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:43:59.01              PeerID 2 CONN ack=  0 (110ms~0) unack=  0, retry= 11, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=11ms) 99.73%, pending=0, dead=0
14:43: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=495/496, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/16, frag=0/0, Errors=0/0
14:44:39.01   Transport - median kBPS [hi/cur] sent = 3.1/1.2, recvd = 1.1/0.9, #p/sec[s/r] = 8.5/8.5, max unsent 0, version err 0, merge 15
14:45:00.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:45:00.01              PeerID 2 CONN ack=  0 (180ms~0) unack=  1, retry= 11, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=15ms) 99.76%, pending=0, dead=0
14:45: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=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
14:45:05.22   Was already stealing a skeleton when told to steal another [mortar_target].
14:45:35.79   Activating screen: Command_Branch
14:45:35.85   We do not have a sync command for peer=2, currentframe=4581
14:45:37.83   Activating screen: NewObjective_widescreen
14:45:40.01   Transport - median kBPS [hi/cur] sent = 3.1/1.0, recvd = 1.1/0.8, #p/sec[s/r] = 8.5/8.6, max unsent 0, version err 0, merge 19
14:46:01.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:46:01.01              PeerID 2 CONN ack=  0 (170ms~0) unack=  1, retry= 13, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=16ms) 99.74%, pending=0, dead=0
14:46:01.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=17/16, frag=0/0, Errors=0/0
14:46:41.01   Transport - median kBPS [hi/cur] sent = 3.1/1.1, recvd = 1.1/0.9, #p/sec[s/r] = 8.6/8.7, max unsent 0, version err 0, merge 21
14:47:02.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:47:02.01              PeerID 2 CONN ack=  0 ( 60ms~0) unack=  0, retry= 13, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=11ms) 99.77%, pending=0, dead=0
14:47:02.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=16/17, frag=0/0, Errors=0/0
14:47:14.79   We do not have a sync command for peer=2, currentframe=5383
14:47:42.01   Transport - median kBPS [hi/cur] sent = 3.1/1.1, recvd = 1.1/0.8, #p/sec[s/r] = 8.6/8.7, max unsent 0, version err 0, merge 27
14:48:03.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:48:03.01              PeerID 2 CONN ack=  0 (140ms~0) unack=  0, retry= 16, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=18ms) 99.73%, pending=0, dead=0
14:48: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=496/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
14:48:43.01   Transport - median kBPS [hi/cur] sent = 3.1/1.1, recvd = 1.1/0.9, #p/sec[s/r] = 8.8/8.9, max unsent 0, version err 0, merge 22
14:48:52.81   We do not have a sync command for peer=2, currentframe=6183
14:49:04.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:49:04.01              PeerID 2 CONN ack=  0 (160ms~0) unack=  1, retry= 17, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=18ms) 99.74%, pending=0, dead=0
14:49:04.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=500/499, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
14:49:19.03   Unabled to do upgrade, CANPRODUCE_NoResources
14:49:20.14   Unabled to do upgrade, CANPRODUCE_NoResources
14:49:44.01   Transport - median kBPS [hi/cur] sent = 3.1/1.0, recvd = 1.1/1.0, #p/sec[s/r] = 8.9/9.0, max unsent 0, version err 0, merge 37
14:49:57.87   We do not have a sync command for peer=2, currentframe=6714
14:49:59.54   Unabled to do upgrade, CANPRODUCE_NoResources
14:50:05.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:50:05.01              PeerID 2 CONN ack=  0 (140ms~0) unack=  1, retry= 17, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=15ms) 99.76%, pending=0, dead=0
14:50:05.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=497/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
14:50:30.84   We do not have a sync command for peer=2, currentframe=6982
14:50:45.01   Transport - median kBPS [hi/cur] sent = 3.1/0.8, recvd = 1.1/0.9, #p/sec[s/r] = 8.6/8.7, max unsent 0, version err 0, merge 22
14:50:50.33   Unabled to do upgrade, CANPRODUCE_NoResources
14:51:03.85   We do not have a sync command for peer=2, currentframe=7250
14:51:06.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:51:06.01              PeerID 2 CONN ack=  0 ( 60ms~0) unack=  1, retry= 18, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=20ms) 99.76%, pending=0, dead=0
14:51: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=495/495, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=16/17, frag=0/0, Errors=0/0
14:51:27.67   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:28.92   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:29.80   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:30.88   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:31.87   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:32.95   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:33.93   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:35.05   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:35.99   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:37.13   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:37.88   We do not have a sync command for peer=2, currentframe=7527
14:51:38.36   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:39.37   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:40.52   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:41.74   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:42.84   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:43.78   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:44.97   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:45.20   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:46.00   Transport - median kBPS [hi/cur] sent = 3.1/1.0, recvd = 1.1/0.9, #p/sec[s/r] = 8.7/8.8, max unsent 0, version err 0, merge 36
14:51:46.04   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:46.88   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:47.95   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:48.80   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:50.04   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:51.08   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:52.04   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:53.18   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:54.17   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:55.16   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:56.16   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:57.36   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:58.22   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:58.48   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:59.46   An ability failed to cast because target validation failed: abilities\sector_artillery
14:51:59.58   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:00.44   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:00.70   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:01.52   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:01.64   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:02.51   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:02.66   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:03.46   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:03.61   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:04.59   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:05.78   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:06.76   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:07.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:52:07.00              PeerID 2 CONN ack=  0 ( 60ms~0) unack=  0, retry= 18, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=13ms) 99.78%, pending=0, dead=0
14:52: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=497/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
14:52:07.77   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:09.01   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:09.83   We do not have a sync command for peer=2, currentframe=7788
14:52:10.07   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:11.19   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:12.12   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:13.33   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:14.31   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:15.12   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:16.10   An ability failed to cast because target validation failed: abilities\sector_artillery
14:52:47.00   Transport - median kBPS [hi/cur] sent = 3.1/0.8, recvd = 1.1/0.7, #p/sec[s/r] = 8.9/8.8, max unsent 0, version err 0, merge 39
14:53:07.71   SimulationController::GenerateSyncCmd - couldn't send all commands, 1 binned.
14:53:08.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:53:08.00              PeerID 2 CONN ack=  0 (160ms~0) unack=  1, retry= 18, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=11ms) 99.79%, pending=0, dead=0
14:53:08.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/498, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=17/17, frag=0/0, Errors=0/0
14:53:15.85   We do not have a sync command for peer=2, currentframe=8326
14:53:48.00   Transport - median kBPS [hi/cur] sent = 3.1/0.8, recvd = 1.1/0.9, #p/sec[s/r] = 8.6/8.9, max unsent 0, version err 0, merge 26
14:54:09.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.106:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:54:09.00              PeerID 2 CONN ack=  0 (120ms~0) unack=  2, retry= 19, highwaterOOS=2 @WINaddr:192.168.1.100:6112; (ping=18ms) 99.79%, pending=0, dead=0
14:54:09.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/18, frag=0/0, Errors=0/0
14:54:16.38   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:17.37   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:18.34   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:19.46   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:20.58   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:21.58   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:22.22   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:22.86   We do not have a sync command for peer=2, currentframe=8868
14:54:23.46   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:24.58   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:25.66   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:26.52   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:27.56   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:28.42   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:28.50   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:29.49   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:29.61   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:30.31   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:30.71   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:31.51   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:31.61   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:32.72   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:32.72   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:33.83   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:33.83   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:34.72   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:34.95   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:35.67   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:36.05   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:36.81   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:37.06   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:37.78   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:38.16   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:38.96   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:39.25   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:40.11   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:40.11   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:41.08   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:41.24   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:42.07   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:42.20   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:43.09   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:43.36   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:44.21   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:44.47   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:45.28   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:45.55   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:45.55   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:46.39   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:46.54   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:47.50   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:47.64   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:48.50   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:48.77   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:49.00   Transport - median kBPS [hi/cur] sent = 3.1/0.8, recvd = 1.1/0.8, #p/sec[s/r] = 8.6/9.0, max unsent 0, version err 0, merge 34
14:54:49.63   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:49.90   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:50.71   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:50.86   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:51.73   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:51.94   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:52.81   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:53.08   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:53.92   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:54.13   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:54.89   We do not have a sync command for peer=2, currentframe=9129
14:54:55.13   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:55.52   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:56.34   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:56.59   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:57.44   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:57.57   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:58.46   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:58.68   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:59.45   An ability failed to cast because target validation failed: abilities\sector_artillery
14:54:59.81   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:00.35   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:00.83   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:01.34   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:01.90   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:02.26   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:02.78   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:03.36   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:03.71   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:04.33   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:04.84   An ability failed to cast because target validation failed: abilities\sector_artillery
14:55:05.33   An ability failed to cast because target validation failed: abilities\sector_artillery
