-- Log file for all dbTracef messages --

13:45:54.79   NetworkManager::Create - creating network manager
13:45:54.79   Read 269 bytes from network datastore login_cache.bin
13:45:54.79   Read 52 bytes from network datastore global_cache.bin
13:45:54.79   Read 28 bytes from network datastore ping_cache.bin
13:45:54.79   QuazalInitializer - static initializing Quazal library
13:45:54.79   PingCache - populating cache with 0 pings
13:45:54.79   Sent message game CompanyOfHeroes started 4144 602 allowtraffic
13:45:54.79   RemoteDLManager - Connection Restored.
13:45:54.79   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
13:45:54.84   WinTransport - CreateSocket exclusive broadcast socket was available.
13:45:54.84   WinTransport - CreateSocket listening for broadcasts on default port
13:45:54.84   WinTransport - Host Name: NgocQuang-PC, aliases: , type=AF_INET, len=4
13:45:54.84   WinTransport - Host IP Address #0: 127.0.0.1
13:45:54.84   WinTransport - Interface #0: ip:192.168.1.100, broadcast:192.168.1.100, flags=IFF_BROADCAST IFF_MULTICAST
13:45:54.84   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
13:45:54.84   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
13:45:54.84   WinTransport - Quazal address string = udp:/address=127.0.0.1;port=6112
13:45:54.84   SessionManager - Peer Header Size = 16 bytes
13:45:54.84   SessionManager - Game Data overhead = 7 bytes
13:45:54.84   SessionManager - Proxy overhead = 7 bytes
13:45:54.84   MessageInternal::CreateChannel: Created channel 47535450
13:45:54.84   Session::Initialize - info, initializing session object, using threads.
13:45:54.84   SessionManager::RegisterSession - Registering new session 03b25ff8
13:45:54.84   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
13:45:54.84   AutomatchInternal: Instantiating
13:45:54.84   PartyInternal: Instantiating
13:45:54.84   MessageInternal::CreateChannel: Created channel 50525459
13:45:54.84   MessageInternal::CreateChannel: Created channel 51434b4d
13:45:54.84   Net::ThreadFunction - Entering network thread function...
13:45:54.85   MessageInternal::CreateChannel: Created channel 534d5347
13:45:54.85   MessageInternal::CreateChannel: Created channel 474d4343
13:45:54.85   MessageInternal::CreateChannel: Created channel 474f424a
13:45:54.85   MessageInternal::CreateChannel: Created channel 4d4f444d
13:45:54.85   MessageInternal::CreateChannel: Created channel 53594e43
13:45:54.85   MessageInternal::DestroyChannel: Destroyed channel 534d5347
13:45:54.85   MessageInternal::DestroyChannel: Destroyed channel 474d4343
13:45:54.85   MessageInternal::DestroyChannel: Destroyed channel 474f424a
13:45:54.85   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
13:45:54.85   MessageInternal::DestroyChannel: Destroyed channel 53594e43
13:45:54.85   GAME -- Available memory: 3071MB Physical RAM, 3069MB Pagefile, 4095 Virtual Address Space
13:45:54.85   Transport - Largest received is now 19
13:45:54.85   Transport::OpenInternal request to WINaddr:127.0.0.1:6112;
13:45:58.22   DLLDriverLinker -- Adding driver 'spDx10.dll'.
13:45:58.22   DLLDriverLinker -- Adding driver 'spDx9.dll'.
13:45:58.22   DLLDriverLinker -- 2 DLL drivers found.
13:45:58.28   SPDx10 -- Adapter [ASUS EAH3450 Series]: 508MB dedicated video memory, 0MB dedicated system memory and 1279MB shared system memory.
13:45:59.94   DLLDriverLinker -- 2 DLL drivers found.
13:46:00.09   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
13:46:00.09   GAME -- Resolution set to 1024x768 (fullscreen).
13:46:00.11   SPDx9 -- Driver Name = atiumdag.dll  Desc = ASUS EAH3450 Series
13:46:00.11   SPDx9 -- Driver Vendor = 0x1002  Device = 0x95C5  SubSys = 0x01E21043  Rev = 0x0000
13:46:00.11   SPDx9 -- Driver Version  Product = 0x0007  Version = 0x000E  SubVersion = 0x00  Build = 5.97
13:46:00.11   SPDx9 -- Driver GUID = {D7B71EE2-D685-11CF-A671-E821A1C2CA35}
13:46:00.64   SPDx9 -- 1774MB available Texture Memory
13:46:00.65   ShaderDatabase: using shader profile [ps30]
13:46:01.22   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
13:46:01.46   GameObjLoader 039cd1b8 - resetting counters
13:46:01.46   GameObjLoader 039cd1b8 - Created loader
13:46:01.46   GameObjLoader 039cd318 - resetting counters
13:46:01.46   GameObjLoader 039cd318 - Created loader
13:46:01.87   GAME -- Beginning FE
13:46:01.87   Sent message game CompanyOfHeroes allowtraffic
13:46:01.87   UIFrontEnd - Loading Front End
13:46:01.87   THREAD: Hyper-Threading Technology Processors are not detected.
13:46:01.98   SOUND -- Initializing ...
13:46:02.72   SOUND -- Initialization completed!
13:46:02.72   UIFrontEnd - Initializing Forms
13:46:04.69   CampaignFilter::BindFilterSpecificWidgets()
13:46:04.75   installed_products = ( )
13:46:04.78   Activating screen: AppLoadingForm
13:46:04.78   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
13:46:04.78   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
13:46:04.78   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
13:46:04.78   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
13:46:05.59   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
13:46:05.59   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
13:46:05.59   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
13:46:05.60   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
13:46:05.60   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
13:46:05.60   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
13:46:05.98   GAME -- Using player profile NGOCQUANG-PC
13:46:09.32   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
13:46:10.28   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
13:46:11.35   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
13:46:11.35   RendezvousManager: CreateSession - starting profile=Guest login
13:46:11.40   GetBackEndServices()->Login: call returned with (5:2) TransportErrorCode::ConnectionFailure.
13:46:11.40   RendezvousManager - unable to connect to the server as guest
13:46:11.41   OnConnect: connect failure
13:46:11.41   RendezvousManager::Shutdown - deleting instance
13:46:11.41   RendezvousManager - terminating all server calls in progress
13:46:11.41   CallManager - terminating all server calls in progress (1 in progress)
13:46:11.41   RendezvousManager - destroying chat handler
13:46:11.41   ~RendezvousManager - m_state = 7, no need for server disconnect
13:46:11.43   OnConnect: this wasnt a reconnect, no need for autologin
13:46:11.45   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
13:46:11.45   CRC & Version Info : 0000025a:ce5073b5:ec3c9e0d eastern_front:602:factionfix.dll 1
13:46:11.45   Activating screen: FEMovie
13:46:11.45   Activating screen: OnlineWidget
13:46:11.45   Activating screen: RelicOnlineLogin
13:46:11.52   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
13:46:11.52   You have a valid version of the game.
13:46:11.53   AccountStore -- Found matching account %%%DefaultAccountName%%%, ignoring for now
13:46:11.53   installed_products = ( DLC1 DLC2 DLC3 )
13:46:11.53   OnLogin: no previous login, auto selecting profile not required
13:46:11.53   Activating screen: MessageBoxPopup
13:46:11.53   Activating screen: MessageBoxPopup
13:46:11.53   ScreenManager - Flushing recursively, due to activated screens during the previous flush, flush number: 1
13:46:33.93   Beginning media verification...
13:46:33.93   Validator type : validators\udro\udro.dll
13:46:33.93   Activating screen: MessageBoxPopup
13:46:33.96   Activating screen: FE_mm_01
13:46:33.96   GAME -- Setting campaign state to 'dlc1'
13:46:33.96   GAME -- Closing state 'dlc1'
13:46:33.96   GAME -- Setting campaign state to 'dlc2'
13:46:33.96   GAME -- Closing state 'dlc2'
13:46:33.97   GAME -- Setting campaign state to 'dlc3'
13:46:33.97   GAME -- Closing state 'dlc3'
13:46:35.33   Activating screen: MessageBoxPopup
13:46:35.33   Created Matchinfo
13:46:35.33   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
13:46:35.33   starting LAN hosting
13:46:35.36   OnJoinAdvertisementSuccess - joined online match, server leave notification required
13:46:35.36   starting local hosting
13:46:35.36   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
13:46:35.36   Allocated route ID=0 for PeerID 1 at WINaddr:127.0.0.1:6112;
13:46:35.36   Transport::OpenInternal request to WINaddr:127.0.0.1:6112;
13:46:35.36   Session::Host sid = F2FFA4E05B86D771, hostURL = , local addresses = WINaddr:127.0.0.1:6112;
13:46:35.36   ValidateCustomData: called with 217 bytes of custom data
13:46:35.36   Host accepted Peer 1 into the match at address list=WINaddr:127.0.0.1:6112;, routes=WINaddr:127.0.0.1:6112;
13:46:35.36   AdvertisementInternal::Process - EVENT_NEWPEER
13:46:35.36   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
13:46:35.38   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
13:46:35.38   hosting - Session is connected
13:46:35.38   Net::Session::SetVisible - session is set to VISIBLE.
13:46:35.39   hosting completed successfully
13:46:35.39   HostAsync - completed with HostResult = 0
13:46:35.39   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
13:46:35.39   Activating screen: OnlineGameSetup
13:46:35.39   MessageInternal::CreateChannel: Created channel 534d5347
13:46:35.40   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:127.0.0.1:6112; (ping=0ms) 100.00%, pending=0, dead=0
13:46:35.40   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=1/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=0/0, voice=0/0, rchk=0/0, nudge=0/0, peerhdr=0/0, proxy=0/0, ping=0/0, frag=0/0, Errors=0/0
13:46:35.40   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:35.40   MatchInternal::SetMatchType - new type 14 - updating server
13:46:35.41   Transport - Largest received is now 485
13:46:35.43   Transport - Largest received is now 541
13:46:35.43   SetVisible called while !IsConnected
13:46:35.43   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:35.43   Activating screen: RelicOnlineChat
13:46:35.43   Activating screen: RelicOnlineNewsScreen
13:46:35.43   Activating screen: RelicOnlineStatsScreen
13:46:35.43   Activating screen: Achievements
13:46:35.44   GAME -- Setting campaign state to 'dlc1'
13:46:35.44   GAME -- Closing state 'dlc1'
13:46:35.44   GAME -- Setting campaign state to 'dlc1'
13:46:35.44   GAME -- Closing state 'dlc1'
13:46:35.45   Transport - Largest received is now 1143
13:46:35.45   GAME -- Setting campaign state to 'dlc2'
13:46:35.45   GAME -- Closing state 'dlc2'
13:46:35.45   GAME -- Setting campaign state to 'dlc3'
13:46:35.45   GAME -- Closing state 'dlc3'
13:46:35.45   GAME -- Setting campaign state to 'dlc1'
13:46:35.45   GAME -- Closing state 'dlc1'
13:46:35.46   Activating screen: GameHistory
13:46:35.46   GAME -- Setting campaign state to 'dlc1'
13:46:35.46   GAME -- Closing state 'dlc1'
13:46:35.46   GAME -- Setting campaign state to 'dlc2'
13:46:35.47   Transport - Largest received is now 1203
13:46:35.47   GAME -- Closing state 'dlc2'
13:46:35.47   GAME -- Setting campaign state to 'dlc3'
13:46:35.47   GAME -- Closing state 'dlc3'
13:46:35.47   Activating screen: OnlineGameSetup
13:46:35.47   Activating screen: RelicOnlineTabs
13:46:35.47   AutomatchInternal::OnHostComplete - Completed Host with success=1
13:46:35.47   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
13:46:35.47   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
13:46:35.48   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:35.48   Net::Session::SetVisible - session is set to VISIBLE.
13:46:35.48   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:35.57   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:35.62   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:37.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:40.36   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:40.36   Net::Session::SetVisible - session is set to VISIBLE.
13:46:40.36   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:40.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:40.44   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:42.45   Activating screen: RaceSelectionPopup
13:46:43.10   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:43.10   Net::Session::SetVisible - session is set to VISIBLE.
13:46:43.10   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:43.13   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:43.16   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:43.61   Activating screen: DynamicPopupMenu
13:46:44.20   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:44.20   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:44.20   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:44.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:44.24   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:44.67   Activating screen: RaceSelectionPopup
13:46:45.29   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:45.29   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:45.30   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:45.31   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:45.34   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:45.84   Activating screen: DynamicPopupMenu
13:46:46.55   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:46.55   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:46.56   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:46.57   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:46.61   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:46.97   Activating screen: DynamicPopupMenu
13:46:47.66   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:47.66   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:47.66   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:47.68   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:47.72   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:48.94   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:48.94   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:48.94   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:48.95   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:48.98   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:50.14   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:50.14   Net::Session::SetVisible - session is set to INVISIBLE.
13:46:50.14   GetMaxFrameTimeFromProfile: players=4 expected FPS=18.066849, bars=5, max avg=0.055, sd=0.000, 1 samples =  0.06
13:46:50.15   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:50.18   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:51.10   GameSetupForm - No win/loss reporting and only a single player, disconnecting
13:46:51.10   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
13:46:51.10   SetVisible called while !IsConnected
13:46:51.10   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:51.11   GameSetupForm - Starting game
13:46:51.11   GameInfo::ResetInfo - SyncLevel set to 0 on reset
13:46:51.11   PopulateGameInfo - random seed:[1309762011], guid:[{5d8c29d8-f74d-4a3f-be10-1b4ddb64287d}], sync level:[2]
13:46:51.11   Error loading [DATA:levelingCurve.lua]
13:46:51.12   Error loading [DATA:levelingCurve.lua]
13:46:51.12   Error loading [DATA:levelingCurve.lua]
13:46:51.12   Error loading [DATA:levelingCurve.lua]
13:46:51.12   MOD - Setting player (0) race to: allies_soviets
13:46:51.12   MOD - Setting player (0) race to: 2
13:46:51.12   MOD - Setting player (1) race to: allies_soviets
13:46:51.12   MOD - Setting player (1) race to: 2
13:46:51.12   MOD - Setting player (2) race to: axis
13:46:51.12   MOD - Setting player (2) race to: 3
13:46:51.12   MOD - Setting player (3) race to: axis
13:46:51.12   MOD - Setting player (3) race to: 3
13:46:51.12   Activating screen: MessageBoxPopup
13:46:51.12   Session is marking the local peer PeerID 1 for death
13:46:51.12   Session::GetEvent DestroyPeer success Peer 1 removed
13:46:51.12   Session::DestroyPeer - deallocating peer 1
13:46:51.12   Session - Requested Disconnect for connectionID 2
13:46:51.12   Deallocated route ID=3 for PeerID 1 at WINaddr:127.0.0.1:6112;
13:46:51.12   Session - Requested Disconnect for connectionID 3
13:46:51.12   Closed route 3<>3 for PeerID 1 at WINaddr:127.0.0.1:6112;
13:46:51.12   AdvertisementInternal::Process - EVENT_DESTROYPEER
13:46:51.12   Destroyed Matchinfo
13:46:51.13   APP -- Game Start
13:46:51.13   Sent message game CompanyOfHeroes allowtraffic
13:46:51.13   GAME -- Setting campaign state to 'dlc1'
13:46:51.13   GAME -- Closing state 'dlc1'
13:46:51.13   GAME -- Setting campaign state to 'dlc2'
13:46:51.13   GAME -- Closing state 'dlc2'
13:46:51.13   GAME -- Setting campaign state to 'dlc3'
13:46:51.13   GAME -- Closing state 'dlc3'
13:46:51.14   Transport - Largest sent is now 8
13:46:51.14   MessageInternal::DestroyChannel: Destroyed channel 534d5347
13:46:51.15   GAME -- Ending FE
13:46:51.15   UIFrontEnd - Unloading Front End
13:46:51.17   SOUND -- Shutting down ...
13:46:51.22   SOUND -- Shutdown completed!
13:46:51.24
13:46:51.24   GAME -- *** Beginning mission 4P_LEMBERG (1 Humans, 3 Computers) ***
13:46:51.24
13:46:51.24   GAME -- Recording game
13:46:51.33   Activating screen: GameLoadScreen
13:46:51.34   ReportGameStartStats - no connection to server unable to report
13:46:51.34   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
13:46:51.34   PeerRemoveAll - flushing local session peer data
13:46:51.34   Session::Reset with reason 999 and MatchInternal::PeerRemoveAll
13:46:51.34   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
13:46:51.65   THREAD: Hyper-Threading Technology Processors are not detected.
13:46:51.73   SOUND -- Initializing ...
13:46:52.47   SOUND -- Initialization completed!
13:46:52.51   PHYSICS: detected processor(s) capable of handling 4 threads.
13:46:52.59   MOD -- Locating MOD for scenario 'DATA:Scenarios\MP\4P_LEMBERG'
13:46:52.59   MOD -- Using Mod 'Eastern_Front'
13:46:52.61   Unable to load/parse precache file [DATA:Scenarios\MP\4P_LEMBERG_precache.lua]
13:46:52.65   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
13:46:52.65   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
13:46:55.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.4, max unsent 0, version err 0, merge 0
13:46:56.19   GameObjLoader - upgrading load_count from 0 to 1046
13:46:59.68   GameObjLoader - upgrading load_count from 1046 to 1714
13:46:59.76   PHYSICS -- Created node factory 'HVOK'
13:46:59.76   PHYSICS -- Created node factory 'DMMY'
13:47:26.51   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
13:47:46.80   GameObjLoader - upgrading load_count from 41 to 4182
13:47:55.72   GameObjLoader 039cd1b8 - resetting counters
13:47:55.72   GameObjLoader 039cd1b8 - LOAD_DONE
13:47:55.72   GAME - SessionSetup
13:47:55.72   GAME -- Can't initialize scenario pack more than once.
13:47:56.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:47:56.27   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
13:47:56.27   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
13:47:56.27   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
13:47:58.39   GAME - CreateGEWorld in 2670 ms
13:47:58.43   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
13:47:58.84   GAME - SessionSetup finished in 3119 ms
13:47:58.84   GAME - WaterReflectionManagerSetup
13:47:58.84   GAME - WaterReflectionManagerSetup finished in 0 ms
13:47:59.09   MessageInternal::CreateChannel: Created channel 474d4343
[Cope FactionFix] - Trying to hook function
[Cope FactionFix] - Got LuaState @0x06430cf8
[Cope FactionFix] - patched @0x6c225491
[Cope FactionFix] - hooked @0x6c225492
[Cope FactionFix] - patched @0x6c224a07
[Cope FactionFix] - hooked @0x6c224a07
[Cope FactionFix] - Hook installed
13:47:59.45   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilateNoPop.scar] succeeded.
13:47:59.78   MOD -- Player  (unused player) (frame 0) (KillPlayer)
13:47:59.78   MOD -- Player  (unused player) (frame 0) (KillPlayer)
13:47:59.78   MOD -- Player  (unused player) (frame 0) (KillPlayer)
13:47:59.78   MOD -- Player  (unused player) (frame 0) (KillPlayer)
13:48:00.35   MessageInternal::CreateChannel: Created channel 4d4f444d
13:48:01.14   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
13:48:01.15   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
13:48:01.15   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
13:48:01.15   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
13:48:01.15   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
13:48:01.76   SPEECHMANAGER -- Loaded in 0.545950 seconds
13:48:03.80   GameObjLoader - upgrading load_count from 0 to 408
13:48:04.16   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
13:48:05.94   GameObjLoader 039cd318 - resetting counters
13:48:05.94   GameObjLoader 039cd318 - LOAD_DONE
13:48:08.38   PreloadResources took 2380ms.
13:48:08.49   GAME -- Loading completed (77 seconds)
13:48:08.49   SIM -- Setting SyncErrorChecking level to None
13:48:18.23   Activating screen: GameScreen
13:48:18.23   Activating screen: Decorators
13:48:18.23   Activating screen: Taskbar
13:48:18.23   Activating screen: SubtitleScreen
13:48:18.23   Activating screen: TextOverlayScreen
13:48:18.37   PerformanceRecorder::StartRecording for game size 4
13:48:18.37   GAME -- Starting mission...
13:48:19.24   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
13:48:19.24   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
13:48:19.24   MOD -- Player CPU - Expert set to AI Type: AI Player (frame 1) (CmdAI)
13:48:23.90   Warning: non auto-match upgrade not found in AE, tuning
13:48:38.54   Activating screen: Command_Tree
13:48:40.33   Activating screen: Command_Branch
13:48:51.77   Activating screen: Command_Branch
13:48:53.58   Activating screen: NewObjective
13:48:55.88   Warning: binding repeat_2(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
13:48:57.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:49:44.78   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
13:49:55.54   Warning: binding repeat_3(Ability: abilities\smg_pps) -- ui index '0' out of bounds; range is [1, 12]
13:49:55.54   Warning: binding repeat_4(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
13:49:58.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:50:37.52   Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
13:50:59.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:52:00.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0
13:52:06.85   Activating screen: Command_Branch
13:53:01.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:53:18.43   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:53:18.43   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:53:18.43   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
13:53:33.92   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:53:33.92   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:53:33.92   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
13:54:02.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0
13:55:03.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:55:44.02   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:55:44.02   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:55:44.02   Unable to bind updater for fx [fx_damage\tech_tree\white_lingering_smoke_combo_00].  It could be looping in a fire-n-forget action.
13:56:04.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:56:04.57   Unable to bind updater for fx [fx_special\production_exhaust].  It could be looping in a fire-n-forget action.
13:56:04.57   Muting spew of bind updater.  Fix art and re-test.
13:57:05.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:57:15.81   Activating screen: Command_Branch
13:58:06.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
13:59:07.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:00:08.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:01:09.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:02:05.10   Activating screen: Command_Branch
14:02:10.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:03:11.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0
14:04:12.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:05:13.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:05:33.28   Unabled to do upgrade, CANPRODUCE_NoResources
14:06:14.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:06:17.41   Activating screen: Command_Branch
14:07:01.22   FXShader::SetVariable( "tex0", T ) uses unknown variable.
14:07:15.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:07:37.90   Unabled to do upgrade, CANPRODUCE_NoResources
14:07:38.94   Unabled to do upgrade, CANPRODUCE_NoResources
14:07:40.02   Unabled to do upgrade, CANPRODUCE_NoResources
14:08:16.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.3, max unsent 0, version err 0, merge 0
14:09:02.22   Activating screen: Command_Branch
14:09:17.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:09:46.02   Activating screen: Command_Branch
14:10:18.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:11:19.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:12:20.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:12:50.54   Activating screen: Command_Branch
14:12:52.04   Activating screen: NewObjective
14:13:21.00   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:14:22.01   Transport - median kBPS [hi/cur] sent = 0.0/0.0, recvd = 2.2/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
