-- Log file for all dbTracef messages --

14:05:47.49   NetworkManager::Create - creating network manager
14:05:47.51   Read 814 bytes from network datastore login_cache.bin
14:05:47.51   Read 133536 bytes from network datastore global_cache.bin
14:05:47.58   Read 56800 bytes from network datastore ping_cache.bin
14:05:47.58   QuazalInitializer - static initializing Quazal library
14:05:47.59   PingCache - populating cache with 3154 pings
14:05:47.63   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
14:05:47.63   WinTransport - CreateSocket exclusive broadcast socket was available.
14:05:47.63   WinTransport - CreateSocket listening for broadcasts on default port
14:05:47.64   WinTransport - Host Name: SC-PC, aliases: , type=AF_INET, len=4
14:05:47.64   WinTransport - Host IP Address #0: 192.168.1.54
14:05:47.64   WinTransport - Host IP Address #1: 25.63.38.120
14:05:47.64   WinTransport - WARNING, MultipleNetwork adapters may cause problems
14:05:47.64   WinTransport - Interface #0: ip:25.63.38.120, broadcast:25.63.38.120, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
14:05:47.64   WinTransport - Interface #1: ip:192.168.1.54, broadcast:192.168.1.54, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
14:05:47.64   WinTransport - Interface #2: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
14:05:47.64   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:05:47.64   WinTransport - Quazal address string = udp:/address=192.168.1.54;port=6112
14:05:47.64   SessionManager - Peer Header Size = 16 bytes
14:05:47.64   SessionManager - Game Data overhead = 7 bytes
14:05:47.64   SessionManager - Proxy overhead = 7 bytes
14:05:47.64   MessageInternal::CreateChannel: Created channel 47535450
14:05:47.64   Session::Initialize - info, initializing session object, using threads.
14:05:47.64   SessionManager::RegisterSession - Registering new session 08774448
14:05:47.64   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:05:47.64   AutomatchInternal: Instantiating
14:05:47.64   PartyInternal: Instantiating
14:05:47.64   MessageInternal::CreateChannel: Created channel 50525459
14:05:47.64   MessageInternal::CreateChannel: Created channel 51434b4d
14:05:47.64   Net::ThreadFunction - Entering network thread function...
14:05:47.66   Transport - Largest received is now 19
14:05:47.66   Transport::OpenInternal request to WINaddr:192.168.1.54:6112;
14:05:47.77   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
14:05:47.77   MessageInternal::CreateChannel: Created channel 534d5347
14:05:47.77   MessageInternal::CreateChannel: Created channel 474d4343
14:05:47.77   MessageInternal::CreateChannel: Created channel 474f424a
14:05:47.77   MessageInternal::CreateChannel: Created channel 4d4f444d
14:05:47.77   MessageInternal::CreateChannel: Created channel 53594e43
14:05:47.77   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:05:47.77   MessageInternal::DestroyChannel: Destroyed channel 474d4343
14:05:47.77   MessageInternal::DestroyChannel: Destroyed channel 474f424a
14:05:47.77   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
14:05:47.77   MessageInternal::DestroyChannel: Destroyed channel 53594e43
14:05:47.77   GAME -- Available memory: 3831MB Physical RAM, 3830MB Pagefile, 4095 Virtual Address Space
14:05:51.65   DLLDriverLinker -- Adding driver 'spDx10.dll'.
14:05:51.76   DLLDriverLinker -- Adding driver 'spDx9.dll'.
14:05:51.76   DLLDriverLinker -- 2 DLL drivers found.
14:05:51.86   SPDx10 -- Adapter [ATI Radeon HD 4200]: 252MB dedicated video memory, 0MB dedicated system memory and 1659MB shared system memory.
14:05:53.91   DLLDriverLinker -- 2 DLL drivers found.
14:05:54.03   SPOOGE - Driver[DirectX9 rendering device] version[4,36]
14:05:54.03   GAME -- Resolution set to 1280x720 (fullscreen).
14:05:54.10   SPDx9 -- Driver Name = aticfx32.dll  Desc = ATI Radeon HD 4200
14:05:54.10   SPDx9 -- Driver Vendor = 0x1002  Device = 0x9710  SubSys = 0x2A92103C  Rev = 0x0000
14:05:54.10   SPDx9 -- Driver Version  Product = 0x0008  Version = 0x0011  SubVersion = 0x00  Build = 11.14
14:05:54.10   SPDx9 -- Driver GUID = {D7B71EE2-D450-11CF-D677-980ABEC2C535}
14:05:54.94   SPDx9 -- 1897MB available Texture Memory
14:05:55.01   ShaderDatabase: using shader profile [ps15]
14:05:55.68   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
14:05:56.32   GameObjLoader 0ab376b0 - resetting counters
14:05:56.32   GameObjLoader 0ab376b0 - Created loader
14:05:56.32   GameObjLoader 0ab37810 - resetting counters
14:05:56.32   GameObjLoader 0ab37810 - Created loader
14:05:56.92   GAME -- Beginning FE
14:05:56.92   Sent message game CompanyOfHeroes started 6424 602 allowtraffic
14:05:56.92   RemoteDLManager - Connection Restored.
14:05:56.92   UIFrontEnd - Loading Front End
14:05:56.92   THREAD: Hyper-Threading Technology Processors are not detected.
14:05:57.06   SOUND -- Initializing ...
14:05:57.16   INNIMapDCA Key not found: sp_speechducker::time
14:05:57.76   SOUND -- Initialization completed!
14:05:57.76   UIFrontEnd - Initializing Forms
14:05:59.56   CampaignFilter::BindFilterSpecificWidgets()
14:05:59.71   Activating screen: MovieScreen
14:05:59.71   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
14:06:05.29   Activating screen: AppLoadingForm
14:06:05.29   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:06:07.48   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
14:06:07.48   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
14:06:07.48   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
14:06:07.60   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
14:06:07.61   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
14:06:07.62   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
14:06:08.02   GAME -- Using player profile SC-PC
14:06:08.23   Dx9Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
14:06:08.40   Dx9Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
14:06:10.17   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
14:06:10.17   RendezvousManager: CreateSession - starting profile=Guest login
14:06:11.15   RendezvousManager: Login complete and successfull
14:06:11.17   RendezvousManager initialized
14:06:11.30   Current server English:live version is 602.0, client is 602.0
14:06:11.32   OnConnect: successful connection established, enabling reconnect
14:06:11.32   OnConnect: this wasnt a reconnect, no need for autologin
14:06:11.33   Logging in EssCee on controller:0
14:06:11.68   Login completed: ACCOUNT_VALIDATED
14:06:11.68   Found 1 profiles for account EssCee
14:06:11.68   Found profile: EssCee
14:06:11.69   installed_products = ( COH CXP1 DLC1 DLC2 DLC3 )
14:06:11.69   OnLogin: no previous login, auto selecting profile not required
14:06:11.72   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:06:11.73   CRC & Version Info : 0000025a:c1dd11bc:ec3c9e0d eastern_front:602:factionfix.dll 1
14:06:11.75   Activating screen: FEMovie
14:06:11.75   Activating screen: OnlineWidget
14:06:11.75   Activating screen: RelicOnlineProfileSelect
14:06:11.84   SetupProductLoadingArt - choosing bgArt = 4 (gold=0)
14:06:11.84   Activating screen: RelicOnlineWait
14:06:11.84   RendezvousManager - destroying chat handler
14:06:11.84   RendezvousManager: CreateSession - starting logout profile = 100:Guest
14:06:12.02   RendezvousManager: Logout complete
14:06:12.02   RendezvousManager - terminating all server calls in progress
14:06:12.02   CallManager - terminating all server calls in progress (1 in progress)
14:06:12.02   RendezvousManager: OnCredentialsEvent - starting profile login
14:06:13.01   RendezvousManager: Login complete and successfull
14:06:13.01   RendezvousManager - creating chat handler
14:06:13.01   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
14:06:13.20   QuazalSelectProfileAsync - Got UserID
14:06:13.25   Transport - Largest sent is now 16
14:06:13.35   Transport - Largest received is now 42
14:06:13.39   GetUserStats requested stats for PIDs ( 4488399 ) (best:0, full:1)
14:06:13.50   SelectProfileAsync - RegisterLocalURLs public [udp:/address=98.208.48.246;port=6112;PID=4488399;RVCID=69734361], private [udp:/address=192.168.1.54;port=6112;PID=4488399]
14:06:13.62   QuazalSelectProfileAsync - Got Full Stats
14:06:13.75   GetAutomatchMaps: Got [32] maps
14:06:13.79   PopulateArmyListBox - skipping race 2
14:06:13.79   PopulateArmyListBox - skipping race 0
14:06:13.79   PopulateArmyListBox - skipping race 1
14:06:13.79   PopulateArmyListBox - skipping race 3
14:06:13.79   AutoMatchForm::OnArmySelectionChanged - sending request info
14:06:13.79   GetMaxFrameTimeFromProfile: players=2 expected FPS=14.205752, bars=4, max avg=0.051, sd=0.019, 5 samples =  0.03 0.07 0.04 0.04 0.08
14:06:13.79   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
14:06:13.79   QuazalSelectProfileAsync - Got Automatch maps
14:06:15.25   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
14:06:15.41   Profile [00000000:00447ccf] selected on controller#0
14:06:15.41   Activating screen: FEMovie
14:06:15.41   Activating screen: OnlineWidget
14:06:15.41   Activating screen: FE_mm_01
14:06:15.41   Activating screen: RelicOnlineWait
14:06:15.42   GAME -- Setting campaign state to 'coh'
14:06:15.42   GAME -- Closing state 'coh'
14:06:15.47   GAME -- Setting campaign state to 'cxp2'
14:06:15.47   GAME -- Closing state 'cxp2'
14:06:15.48   GAME -- Setting campaign state to 'cxp1'
14:06:15.48   GAME -- Closing state 'cxp1'
14:06:15.48   GAME -- Setting campaign state to 'dlc1'
14:06:15.48   GAME -- Closing state 'dlc1'
14:06:15.49   GAME -- Setting campaign state to 'dlc2'
14:06:15.49   GAME -- Closing state 'dlc2'
14:06:15.49   GAME -- Setting campaign state to 'dlc3'
14:06:15.49   GAME -- Closing state 'dlc3'
14:06:19.47   Activating screen: MessageBoxPopup
14:06:19.47   Created Matchinfo
14:06:19.47   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
14:06:19.47   starting online hosting
14:06:19.49   OnlineHostAsync: initiating CallCreateMatch
14:06:19.62   OnlineHostAsync: created gid=270260025
14:06:19.63   Transport - Largest sent is now 79
14:06:19.69   RendezvousNotifier - Received Participate ParticipationEvent.
14:06:19.74   Transport - Largest received is now 105
14:06:19.89   OnlineHostAsync - RegisterLocalURLs public [udp:/address=98.208.48.246;port=6112;PID=4488399;RVCID=69734361], private [udp:/address=192.168.1.54;port=6112;PID=4488399]
14:06:20.08   OnlineHostAsync: initiating UpdateSessionURL [gid=270260025, url=udp:/address=98.208.48.246;port=6112;PID=4488399;RVCID=69734361]
14:06:20.32   OnJoinAdvertisementSuccess - joined online match, server leave notification required
14:06:20.32   starting local hosting
14:06:20.32   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
14:06:20.32   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.1.54:6112;
14:06:20.32   Transport::OpenInternal request to WINaddr:192.168.1.54:6112;
14:06:20.32   Session::Host sid = 101BD739, hostURL = , local addresses = WINaddr:192.168.1.54:6112;
14:06:20.32   ValidateCustomData: called with 621 bytes of custom data
14:06:20.32   Host accepted Peer 1 into the match at address list=WINaddr:192.168.1.54:6112;, routes=WINaddr:192.168.1.54:6112;
14:06:20.32   AdvertisementInternal::Process - EVENT_NEWPEER
14:06:20.32   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
14:06:20.34   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
14:06:20.34   hosting - Session is connected
14:06:20.34   Net::Session::SetVisible - session is set to INVISIBLE.
14:06:20.36   hosting completed successfully
14:06:20.36   HostAsync - completed with HostResult = 0
14:06:20.36   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
14:06:20.36   Activating screen: OnlineGameSetup
14:06:20.36   MessageInternal::CreateChannel: Created channel 534d5347
14:06:20.36   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.54:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:06:20.36   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:06:20.36   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:06:20.36   MatchInternal::SetMatchType - new type 14 - updating server
14:06:20.38   SetVisible called while !IsConnected
14:06:20.38   GetMaxFrameTimeFromProfile: players=2 expected FPS=14.205752, bars=4, max avg=0.051, sd=0.019, 5 samples =  0.03 0.07 0.04 0.04 0.08
14:06:20.39   Activating screen: RelicOnlineChat
14:06:20.39   Activating screen: RelicOnlineNewsScreen
14:06:20.45   Activating screen: RelicOnlineStatsScreen
14:06:20.45   Activating screen: Achievements
14:06:20.45   GAME -- Setting campaign state to 'coh'
14:06:20.45   GAME -- Closing state 'coh'
14:06:20.45   GAME -- Setting campaign state to 'cxp2'
14:06:20.45   GAME -- Closing state 'cxp2'
14:06:20.45   GAME -- Setting campaign state to 'cxp1'
14:06:20.45   GAME -- Closing state 'cxp1'
14:06:20.45   GAME -- Setting campaign state to 'dlc1'
14:06:20.46   GAME -- Closing state 'dlc1'
14:06:20.46   GAME -- Setting campaign state to 'dlc2'
14:06:20.46   GAME -- Closing state 'dlc2'
14:06:20.46   GAME -- Setting campaign state to 'dlc3'
14:06:20.46   GAME -- Closing state 'dlc3'
14:06:20.46   GAME -- Setting campaign state to 'dlc1'
14:06:20.46   GAME -- Closing state 'dlc1'
14:06:20.47   Activating screen: GameHistory
14:06:20.47   GAME -- Setting campaign state to 'coh'
14:06:20.49   GAME -- Closing state 'coh'
14:06:20.49   GAME -- Setting campaign state to 'cxp2'
14:06:20.49   GAME -- Closing state 'cxp2'
14:06:20.49   GAME -- Setting campaign state to 'cxp1'
14:06:20.49   GAME -- Closing state 'cxp1'
14:06:20.49   GAME -- Setting campaign state to 'dlc1'
14:06:20.49   GAME -- Closing state 'dlc1'
14:06:20.50   GAME -- Setting campaign state to 'dlc2'
14:06:20.51   GAME -- Closing state 'dlc2'
14:06:20.51   GAME -- Setting campaign state to 'dlc3'
14:06:20.51   GAME -- Closing state 'dlc3'
14:06:20.52   Activating screen: OnlineGameSetup
14:06:20.52   Activating screen: RelicOnlineTabs
14:06:20.52   AutomatchInternal::OnHostComplete - Completed Host with success=1
14:06:20.52   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
14:06:20.52   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
14:06:20.52   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
14:06:20.52   Net::Session::SetVisible - session is set to INVISIBLE.
14:06:20.53   GetMaxFrameTimeFromProfile: players=2 expected FPS=14.205752, bars=4, max avg=0.051, sd=0.019, 5 samples =  0.03 0.07 0.04 0.04 0.08
14:06:20.66   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:06:20.68   GAME -- Setting campaign state to 'coh'
14:06:20.68   GAME -- Closing state 'coh'
14:06:20.68   GAME -- Setting campaign state to 'cxp2'
14:06:20.68   GAME -- Closing state 'cxp2'
14:06:20.68   GAME -- Setting campaign state to 'cxp1'
14:06:20.68   GAME -- Closing state 'cxp1'
14:06:20.68   GAME -- Setting campaign state to 'dlc1'
14:06:20.68   GAME -- Closing state 'dlc1'
14:06:20.69   GAME -- Setting campaign state to 'dlc2'
14:06:20.69   GAME -- Closing state 'dlc2'
14:06:20.69   GAME -- Setting campaign state to 'dlc3'
14:06:20.69   GAME -- Closing state 'dlc3'
14:06:20.70   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
14:06:20.79   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:06:21.35   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:06:21.50   Requesting Relic Downloader soft throttle call [GetNewsAsync] has time 802
14:06:21.50   Sent message game CompanyOfHeroes softthrottle
14:06:21.53   Got dlman msg [ack game CompanyOfHeroes softthrottle]
14:06:22.09   GetUserStats requested stats for PIDs ( 4521855 4535529 4895856 4895909 4895923 4913652 ) (best:6, full:0)
14:06:22.09   QueryMatches: Got [54] maps, [165] ids, [17] advertisements, startID [1]
14:06:22.09   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 148 matches
14:06:25.51   QueryMatches: Got [52] maps, [163] ids, [17] advertisements, startID [270257383]
14:06:25.51   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 129 matches
14:06:30.74   QueryMatches: Got [54] maps, [165] ids, [17] advertisements, startID [270258699]
14:06:30.75   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 114 matches
14:06:35.63   QueryMatches: Got [54] maps, [171] ids, [17] advertisements, startID [270259273]
14:06:35.63   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 103 matches
14:06:40.63   QueryMatches: Got [53] maps, [166] ids, [17] advertisements, startID [270259509]
14:06:40.64   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 82 matches
14:06:45.54   QueryMatches: Got [51] maps, [162] ids, [17] advertisements, startID [270259674]
14:06:45.55   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 61 matches
14:06:46.38   SPDx9 -- 1897MB available Texture Memory
14:06:48.00   Transport - median kBPS [hi/cur] sent = 0.1/0.0, recvd = 0.1/0.0, #p/sec[s/r] = 0.0/0.2, max unsent 0, version err 0, merge 0
14:06:50.50   QueryMatches: Got [47] maps, [160] ids, [17] advertisements, startID [270259786]
14:06:50.50   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 45 matches
14:06:50.57   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:06:50.57   MatchInternal::SetMatchType - new type 17 - updating server
14:06:50.57   Net::Session::SetVisible - session is set to INVISIBLE.
14:06:50.58   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:06:50.58   Net::Session::SetVisible - session is set to INVISIBLE.
14:06:50.58   GetMaxFrameTimeFromProfile: players=8 expected FPS=16.838373, bars=5, max avg=0.040, sd=0.020, 5 samples =  0.06 0.02 0.06 0.02 0.03
14:06:50.66   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:06:50.67   UpdateMatch: Call to UpdateGathering started, matchTypeID = 17.
14:06:50.71   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:06:55.41   QueryMatches: Got [45] maps, [160] ids, [17] advertisements, startID [270259907]
14:06:55.41   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 30 matches
14:07:00.38   QueryMatches: Got [47] maps, [163] ids, [17] advertisements, startID [270259989]
14:07:00.39   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 19 matches
14:07:05.43   QueryMatches: Got [48] maps, [162] ids, [17] advertisements, startID [270260073]
14:07:05.44   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 6 matches
14:07:08.44   Transport - Largest sent is now 80
14:07:10.39   QueryMatches: Got [48] maps, [161] ids, [17] advertisements, startID [270260114]
14:07:10.40   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 3 matches
14:07:13.68   Activating screen: DynamicPopupMenu
14:07:15.51   QueryMatches: Got [51] maps, [159] ids, [17] advertisements, startID [270253995]
14:07:15.52   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 10 matches
14:07:20.66   QueryMatches: Got [53] maps, [158] ids, [17] advertisements, startID [270258564]
14:07:20.66   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 14 matches
14:07:21.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.54:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:07:21.01   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=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:07:23.80   RNT_ReportPing: user 3174203:Duschka CANNOT connect pingMS=65535
14:07:25.63   QueryMatches: Got [51] maps, [156] ids, [17] advertisements, startID [270259190]
14:07:25.63   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 13 matches
14:07:30.50   QueryMatches: Got [51] maps, [157] ids, [17] advertisements, startID [270259474]
14:07:30.50   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 16 matches
14:07:35.49   QueryMatches: Got [52] maps, [153] ids, [17] advertisements, startID [270259732]
14:07:35.50   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 15 matches
14:07:36.09   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:07:36.09   Net::Session::SetVisible - session is set to INVISIBLE.
14:07:36.09   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:07:36.10   MatchInternal::SetMatchState - state 1 - updating server
14:07:36.10   Net::Session::SetVisible - session is set to INVISIBLE.
14:07:36.10   MatchSetup: Sending start game message!
14:07:36.10   GameSetupForm - Starting game
14:07:36.10   GameInfo::ResetInfo - SyncLevel set to 0 on reset
14:07:36.10   PopulateGameInfo - random seed:[1354831656], guid:[{8ee32e32-4d6d-4518-a148-001306cc1776}], sync level:[0]
14:07:36.10   Error loading [DATA:levelingCurve.lua]
14:07:36.10   Error loading [DATA:levelingCurve.lua]
14:07:36.10   MOD - Setting player (0) race to: allies
14:07:36.10   MOD - Setting player (0) race to: 1
14:07:36.10   MOD - Setting player (1) race to: axis
14:07:36.10   MOD - Setting player (1) race to: 3
14:07:36.11   OnlineUpdateStateAsync: initiating state change, id = 270260025, state=2
14:07:36.11   GameSetupForm - UpdateMatchType: Setting match type to 17: STONEWALL
14:07:36.11   Net::Session::SetVisible - session is set to INVISIBLE.
14:07:36.11   GetMaxFrameTimeFromProfile: players=8 expected FPS=16.838373, bars=5, max avg=0.040, sd=0.020, 5 samples =  0.06 0.02 0.06 0.02 0.03
14:07:36.12   APP -- Game Start
14:07:36.12   Sent message game CompanyOfHeroes allowtraffic
14:07:36.12   GAME -- Setting campaign state to 'coh'
14:07:36.12   GAME -- Closing state 'coh'
14:07:36.12   GAME -- Setting campaign state to 'cxp2'
14:07:36.12   GAME -- Closing state 'cxp2'
14:07:36.13   GAME -- Setting campaign state to 'cxp1'
14:07:36.13   GAME -- Closing state 'cxp1'
14:07:36.13   GAME -- Setting campaign state to 'dlc1'
14:07:36.13   GAME -- Closing state 'dlc1'
14:07:36.13   GAME -- Setting campaign state to 'dlc2'
14:07:36.15   GAME -- Closing state 'dlc2'
14:07:36.15   GAME -- Setting campaign state to 'dlc3'
14:07:36.15   GAME -- Closing state 'dlc3'
14:07:36.16   MessageInternal::DestroyChannel: Destroyed channel 534d5347
14:07:36.17   GAME -- Ending FE
14:07:36.17   UIFrontEnd - Unloading Front End
14:07:36.18   SOUND -- Shutting down ...
14:07:36.23   SOUND -- Shutdown completed!
14:07:36.24
14:07:36.24   GAME -- *** Beginning mission stonewall_boucamps (1 Humans, 1 Computers) ***
14:07:36.24
14:07:36.35   GAME -- Recording game
14:07:36.43   Activating screen: GameLoadScreen
14:07:36.43   UpdateMatch: Call to UpdateGathering started, matchTypeID = 17.
14:07:36.43   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
14:07:36.63   OnlineUpdateStateAsync: updated state for gid=270260025
14:07:36.71   THREAD: Hyper-Threading Technology Processors are not detected.
14:07:36.75   SOUND -- Initializing ...
14:07:37.43   SOUND -- Initialization completed!
14:07:37.46   PHYSICS: detected processor(s) capable of handling 4 threads.
14:07:37.48   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\ops\stonewall\stonewall_boucamps'
14:07:37.48   MOD -- Using Mod 'Eastern_Front_OPS'
14:07:37.48   MOD -- Mounting Eastern_Front_OPS, 1.0
14:07:37.48   FILESYSTEM -- filepath failure, path does not exist 'CoH\Ops\Data\'
14:07:37.48   FILESYSTEM -- filepath failure, path does not exist 'C:\Users\SC\Documents\My Games\Company of Heroes\CoH\Ops\Data\'
14:07:37.60   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:07:37.60   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:07:49.01   Transport - median kBPS [hi/cur] sent = 0.9/0.1, recvd = 0.8/0.1, #p/sec[s/r] = 3.0/2.5, max unsent 0, version err 0, merge 0
14:08:00.17   WW2Mod -- PropertyBagManager Loaded in 22.608999s
14:08:00.39   Unable to load/parse precache file [DATA:scenarios\mp\ops\stonewall\stonewall_boucamps_precache.lua]
14:08:02.36   QueryMatches: Got [52] maps, [157] ids, [17] advertisements, startID [270259868]
14:08:02.37   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 31 matches
14:08:02.52   GameObjLoader - upgrading load_count from 0 to 1009
14:08:05.06   GameObjLoader - upgrading load_count from 1009 to 1588
14:08:05.13   PHYSICS -- Created node factory 'HVOK'
14:08:05.13   PHYSICS -- Created node factory 'DMMY'
14:08:05.98   QueryMatches: Got [50] maps, [153] ids, [17] advertisements, startID [270260002]
14:08:05.99   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 32 matches
14:08:09.91   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\coh_m01_cue_02_upper_load.smf'.  Expensive operation
14:08:10.77   QueryMatches: Got [53] maps, [151] ids, [17] advertisements, startID [270260097]
14:08:10.78   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 24 matches
14:08:14.87   GameObjLoader - upgrading load_count from 35 to 3632
14:08:15.83   QueryMatches: Got [52] maps, [149] ids, [17] advertisements, startID [270260217]
14:08:15.84   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 9 matches
14:08:20.70   GameObjLoader 0ab376b0 - resetting counters
14:08:20.70   GameObjLoader 0ab376b0 - LOAD_DONE
14:08:20.70   GAME - SessionSetup
14:08:20.92   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
14:08:20.92   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 1024 x 1024
14:08:20.92   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
14:08:22.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.54:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:08:22.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=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:08:22.24   GAME - CreateGEWorld in 1542 ms
14:08:22.26   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
14:08:22.42   GAME - SessionSetup finished in 1724 ms
14:08:22.42   QueryMatches: Got [52] maps, [142] ids, [17] advertisements, startID [270260284]
14:08:22.43   GAME - WaterReflectionManagerSetup
14:08:22.43   GAME - WaterReflectionManagerSetup finished in 0 ms
14:08:22.43   MessageInternal::CreateChannel: Created channel 474d4343
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.07   MOD -- Player  (unused player) (frame 0) (KillPlayer)
14:08:23.11   MessageInternal::CreateChannel: Created channel 4d4f444d
14:08:24.51   SPEECHMANAGER -- Loaded in 0.496262 seconds
14:08:25.52   GAME -- SimulationController::Pause 0
14:08:25.57   GameObj::OnFatalScarError: Invalid parameter 1 (type expected=const ModPlayer*, received=nil) in function Player_GetRaceName
  Lua             stonewall_boucamps.scar        Ln  974 (Rule_CreepSpawnInit)
  Lua             stonewall_boucamps.scar        Ln  765 (Bouc_MissionPreset)
  Lua             stonewall_boucamps.scar        Ln   80 (OnInit)
14:08:25.57   GAME -- SimulationController::Pause 1
14:08:25.58   GameObj::OnFatalScarError: FATAL Scar Error! - Execution has been paused.  Error calling init function OnInit.
14:08:25.58   GAME -- SimulationController::Pause 1
14:08:25.78   GameObjLoader - upgrading load_count from 0 to 368
14:08:27.19   GameObjLoader 0ab37810 - resetting counters
14:08:27.19   GameObjLoader 0ab37810 - LOAD_DONE
14:08:27.67   PreloadResources took 397ms.
14:08:27.86   GAME -- Loading completed (51 seconds)
14:08:27.86   SIM -- Setting SyncErrorChecking level to Low
14:08:28.29   Activating screen: GameScreen
14:08:28.29   Activating screen: Decorators_widescreen
14:08:28.29   Activating screen: Taskbar_widescreen
14:08:28.29   Activating screen: SubtitleScreen
14:08:28.29   Activating screen: TextOverlayScreen
14:08:28.41   PerformanceRecorder::StartRecording for game size 2
14:08:28.41   GAME -- Starting mission...
14:08:31.78   Activating screen: TacticalMap_widescreen
14:08:31.78   Activating screen: TextOverlayScreen
14:08:35.18   Activating screen: GameScreen
14:08:35.18   Activating screen: Decorators_widescreen
14:08:35.18   Activating screen: Taskbar_widescreen
14:08:35.18   Activating screen: SubtitleScreen
14:08:35.18   Activating screen: TextOverlayScreen
14:08:35.80   Activating screen: TacticalMap_widescreen
14:08:35.80   Activating screen: TextOverlayScreen
14:08:36.39   Activating screen: GameScreen
14:08:36.39   Activating screen: Decorators_widescreen
14:08:36.39   Activating screen: Taskbar_widescreen
14:08:36.39   Activating screen: SubtitleScreen
14:08:36.39   Activating screen: TextOverlayScreen
14:08:50.01   Transport - median kBPS [hi/cur] sent = 0.9/0.0, recvd = 0.8/0.0, #p/sec[s/r] = 0.2/0.2, max unsent 0, version err 0, merge 0
14:09:23.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.1.54:6112; (ping=0ms) 100.00%, pending=0, dead=0
14:09:23.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=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:09:51.01   Transport - median kBPS [hi/cur] sent = 0.9/0.0, recvd = 0.8/0.0, #p/sec[s/r] = 0.1/0.0, max unsent 0, version err 0, merge 0
