-- Log file for all dbTracef messages --

17:50:24.20   NetworkManager::Create - creating network manager
17:50:24.20   Read 567 bytes from network datastore login_cache.bin
17:50:24.20   Read 109962 bytes from network datastore global_cache.bin
17:50:24.20   Read 5266 bytes from network datastore ping_cache.bin
17:50:24.20   QuazalInitializer - static initializing Quazal library
17:50:24.20   PingCache - populating cache with 291 pings
17:50:24.24   Transport - Header Size = 4 bytes + 4 byte nonce + 2 byte consolidation header
17:50:24.30   WinTransport - CreateSocket exclusive broadcast socket was available.
17:50:24.30   WinTransport - CreateSocket listening for broadcasts on default port
17:50:24.31   WinTransport - Host Name: Alex-PC, aliases: , type=AF_INET, len=4
17:50:24.31   WinTransport - Host IP Address #0: 192.168.0.199
17:50:24.31   WinTransport - Interface #0: ip:192.168.0.199, broadcast:192.168.0.199, flags=IFF_UP IFF_BROADCAST IFF_MULTICAST
17:50:24.31   WinTransport - Interface #1: ip:127.0.0.1, broadcast:127.0.0.1, flags=IFF_UP IFF_LOOPBACK IFF_MULTICAST
17:50:24.31   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:50:24.31   WinTransport - Quazal address string = udp:/address=192.168.0.199;port=6112
17:50:24.31   SessionManager - Peer Header Size = 16 bytes
17:50:24.31   SessionManager - Game Data overhead = 7 bytes
17:50:24.31   SessionManager - Proxy overhead = 7 bytes
17:50:24.31   MessageInternal::CreateChannel: Created channel 47535450
17:50:24.31   Session::Initialize - info, initializing session object, using threads.
17:50:24.31   SessionManager::RegisterSession - Registering new session 10f9b618
17:50:24.31   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:50:24.31   AutomatchInternal: Instantiating
17:50:24.31   PartyInternal: Instantiating
17:50:24.31   MessageInternal::CreateChannel: Created channel 50525459
17:50:24.31   MessageInternal::CreateChannel: Created channel 51434b4d
17:50:24.31   Net::ThreadFunction - Entering network thread function...
17:50:24.32   Session::GetState - info, session's state changed to [1:STATE_DISCONNECTED].
17:50:24.32   MessageInternal::CreateChannel: Created channel 534d5347
17:50:24.32   MessageInternal::CreateChannel: Created channel 474d4343
17:50:24.32   MessageInternal::CreateChannel: Created channel 474f424a
17:50:24.32   MessageInternal::CreateChannel: Created channel 4d4f444d
17:50:24.32   MessageInternal::CreateChannel: Created channel 53594e43
17:50:24.32   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:50:24.32   MessageInternal::DestroyChannel: Destroyed channel 474d4343
17:50:24.32   MessageInternal::DestroyChannel: Destroyed channel 474f424a
17:50:24.32   MessageInternal::DestroyChannel: Destroyed channel 4d4f444d
17:50:24.32   MessageInternal::DestroyChannel: Destroyed channel 53594e43
17:50:24.32   GAME -- Available memory: 3325MB Physical RAM, 3526MB Pagefile, 2047 Virtual Address Space
17:50:24.33   Transport - Largest received is now 19
17:50:24.33   Transport::OpenInternal request to WINaddr:192.168.0.199:6112;
17:50:28.73   DLLDriverLinker -- Adding driver 'spDx10.dll'.
17:50:28.74   DLLDriverLinker -- Adding driver 'spDx9.dll'.
17:50:28.74   DLLDriverLinker -- 2 DLL drivers found.
17:50:28.79   SPDx10 -- Adapter [NVIDIA GeForce 8800 GT ]: 497MB dedicated video memory, 0MB dedicated system memory and 1407MB shared system memory.
17:50:31.05   DLLDriverLinker -- 2 DLL drivers found.
17:50:31.22   SPOOGE - Driver[DirectX10 Rendering Device] version[4,36]
17:50:31.22   GAME -- Resolution set to 1680x1050 (fullscreen).
17:50:31.29   SPDx10 -- Adapter Description = NVIDIA GeForce 8800 GT
17:50:31.29   SPDx10 -- Driver Vendor = 0x000010de  Device = 0x00000611  SubSys = 0x080110b0  Rev = 0x000000a2
17:50:31.29   SPDx10 -- Driver Version  Product = 0x0008  Version = 0x0011  SubVersion = 0x00  Build = 196.21
17:50:31.29   SPDx10 -- Driver LUID = 0x00000000-0x0000fc32
17:50:31.29   SPDx10 -- 497MB dedicated video memory, 0MB dedicated system memory and 1407MB shared system memory available.
17:50:31.29   ShaderDatabase: using shader profile [ps40]
17:50:32.97   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
17:50:33.15   SPDx10 -- Gamma Caps - Scale/Offset supported: no, Max: 1.00, Min: 0.00, Number of Control Points: 256.
17:50:33.21   FILESYSTEM -- filepath failure, missing alias 'TOOLSDATA:autoloddecimator.lua'
17:50:33.93   GameObjLoader 0b5600d8 - resetting counters
17:50:33.93   GameObjLoader 0b5600d8 - Created loader
17:50:33.93   GameObjLoader 0b560238 - resetting counters
17:50:33.93   GameObjLoader 0b560238 - Created loader
17:50:34.85   GAME -- Beginning FE
17:50:34.85   Sent message game CompanyOfHeroes started 4612 601 allowtraffic
17:50:34.85   RemoteDLManager - Connection Restored.
17:50:34.85   UIFrontEnd - Loading Front End
17:50:34.85   THREAD: Hyper-Threading Technology Processors are not detected.
17:50:34.97   SOUND -- Initializing ...
17:50:35.05   INNIMapDCA Key not found: sp_speechducker::time
17:50:35.83   SOUND -- Initialization completed!
17:50:35.83   UIFrontEnd - Initializing Forms
17:50:38.06   CampaignFilter::BindFilterSpecificWidgets()
17:50:38.16   Activating screen: AppLoadingForm
17:50:38.16   SetupProductLoadingArt - choosing bgArt = 0 (gold=0)
17:50:38.16   Got dlman msg [dlmanager version 1.0 peertraffic 1 uploadlimit 2147483647 seedratio 3]
17:50:39.33   GAME -- Loaded campaign 'Invasion of Normandy' (DATA:SCENARIOS\SP\COH.CAMP) with 15 missions, [coh]
17:50:39.33   GAME -- Loaded campaign 'Liberation of Caen' (DATA:SCENARIOS\SP\CXP1.CAMP) with 9 missions, [cxp1]
17:50:39.34   GAME -- Loaded campaign 'Operation Market Garden' (DATA:SCENARIOS\SP\CXP2.CAMP) with 8 missions, [cxp2]
17:50:39.38   GAME -- Loaded campaign 'Falaise Pocket' (DATA:SCENARIOS\SP\DLC3.CAMP) with 3 missions, [dlc3]
17:50:39.40   GAME -- Loaded campaign 'Causeway' (DATA:SCENARIOS\SP\DLC2.CAMP) with 3 missions, [dlc2]
17:50:39.40   GAME -- Loaded campaign 'Tiger Ace' (DATA:SCENARIOS\SP\DLC1.CAMP) with 3 missions, [dlc1]
17:50:39.77   GAME -- Using player profile ALEX-PC
17:50:40.93   Dx10Program : Unable to find shader script for 'fxshader_multiply' in the ShaderDatabase.
17:50:41.70   Dx10Program : Unable to find shader script for 'fxshader_depthadditive' in the ShaderDatabase.
17:50:42.88   QuazalLoginService - *** Connecting to server: reliclive.quazal.net:30260
17:50:42.88   RendezvousManager: CreateSession - starting profile=Guest login
17:50:44.04   RendezvousManager: Login complete and successfull
17:50:44.04   RendezvousManager initialized
17:50:44.16   Current server English:live version is 601.0, client is 601.0
17:50:44.16   OnConnect: successful connection established, enabling reconnect
17:50:44.16   OnConnect: this wasnt a reconnect, no need for autologin
17:50:44.16   Logging in xB1aSTeRx on controller:0
17:50:44.50   Login completed: ACCOUNT_VALIDATED
17:50:44.50   Found 1 profiles for account xB1aSTeRx
17:50:44.50   Found profile: xB1aSTeRx
17:50:44.50   installed_products = ( DLC1 DLC2 DLC3 CXP1 COH )
17:50:44.50   OnLogin: no previous login, auto selecting profile not required
17:50:44.51   SetupProductLoadingArt - choosing bgArt = 3 (gold=0)
17:50:44.51   CRC & Version Info : 00000259:6aa29d62:48d2c5df eastern_front:601:ww2mod.dll 1
17:50:44.51   Activating screen: FEMovie
17:50:44.51   Activating screen: OnlineWidget
17:50:44.51   Activating screen: RelicOnlineProfileSelect
17:50:44.51   SetupProductLoadingArt - choosing bgArt = 3 (gold=0)
17:50:44.51   Activating screen: RelicOnlineWait
17:50:44.51   RendezvousManager - destroying chat handler
17:50:44.51   RendezvousManager: CreateSession - starting logout profile = 100:Guest
17:50:44.70   RendezvousManager: Logout complete
17:50:44.70   RendezvousManager - terminating all server calls in progress
17:50:44.70   CallManager - terminating all server calls in progress (1 in progress)
17:50:44.70   RendezvousManager: OnCredentialsEvent - starting profile login
17:50:45.76   RendezvousManager: Login complete and successfull
17:50:45.76   RendezvousManager - creating chat handler
17:50:45.76   RendezvousManager::CreateNATTraversalClient - NAT traversal available.
17:50:45.92   QuazalSelectProfileAsync - Got UserID
17:50:45.94   Transport - Largest sent is now 16
17:50:46.06   Transport - Largest received is now 42
17:50:46.07   GetUserStats requested stats for PIDs ( 3052293 ) (best:0, full:1)
17:50:46.19   SelectProfileAsync - RegisterLocalURLs public [udp:/address=90.231.136.27;port=6112;PID=3052293;RVCID=87616205], private [udp:/address=192.168.0.199;port=6112;PID=3052293]
17:50:46.33   QuazalSelectProfileAsync - Got Full Stats
17:50:46.49   GetAutomatchMaps: Got [32] maps
17:50:46.50   PopulateArmyListBox - skipping race 2
17:50:46.50   PopulateArmyListBox - skipping race 0
17:50:46.50   PopulateArmyListBox - skipping race 1
17:50:46.50   PopulateArmyListBox - skipping race 3
17:50:46.50   AutoMatchForm::OnArmySelectionChanged - sending request info
17:50:46.50   GetMaxFrameTimeFromProfile: players=2 expected FPS=100.000000, bars=5, max avg=0.000, sd=0.000, 0 samples =
17:50:46.50   AutoMatchForm::OnMatchTypeSelectionChanged - sending team info
17:50:46.51   QuazalSelectProfileAsync - Got Automatch maps
17:50:47.36   QuazalSelectProfileAsync - GetFriends result - CacheState = 1
17:50:47.49   Profile [00000000:002e9305] selected on controller#0
17:50:47.49   Activating screen: FEMovie
17:50:47.49   Activating screen: OnlineWidget
17:50:47.49   Activating screen: FE_mm_01
17:50:47.49   Activating screen: RelicOnlineWait
17:50:47.49   GAME -- Setting campaign state to 'coh'
17:50:47.49   GAME -- Closing state 'coh'
17:50:47.50   GAME -- Setting campaign state to 'cxp2'
17:50:47.50   GAME -- Closing state 'cxp2'
17:50:47.50   GAME -- Setting campaign state to 'cxp1'
17:50:47.50   GAME -- Closing state 'cxp1'
17:50:47.51   GAME -- Setting campaign state to 'dlc1'
17:50:47.51   GAME -- Closing state 'dlc1'
17:50:47.51   GAME -- Setting campaign state to 'dlc2'
17:50:47.51   GAME -- Closing state 'dlc2'
17:50:47.52   GAME -- Setting campaign state to 'dlc3'
17:50:47.52   GAME -- Closing state 'dlc3'
17:50:49.86   Activating screen: MessageBoxPopup
17:50:49.86   Created Matchinfo
17:50:49.86   Session::Reset with reason 999 and AdvertisementInternal::ResetSession()
17:50:49.86   starting online hosting
17:50:49.87   OnlineHostAsync: initiating CallCreateMatch
17:50:50.02   OnlineHostAsync: created gid=152010242
17:50:50.02   Transport - Largest sent is now 79
17:50:50.07   RendezvousNotifier - Received Participate ParticipationEvent.
17:50:50.15   Transport - Largest received is now 105
17:50:50.28   OnlineHostAsync - RegisterLocalURLs public [udp:/address=90.231.136.27;port=6112;PID=3052293;RVCID=87616205], private [udp:/address=192.168.0.199;port=6112;PID=3052293]
17:50:50.42   OnlineHostAsync: initiating UpdateSessionURL [gid=152010242, url=udp:/address=90.231.136.27;port=6112;PID=3052293;RVCID=87616205]
17:50:50.55   OnJoinAdvertisementSuccess - joined online match, server leave notification required
17:50:50.55   starting local hosting
17:50:50.55   Transport::OpenInternal request to WINaddr:255.255.255.255:6112;
17:50:50.55   Allocated route ID=0 for PeerID 1 at WINaddr:192.168.0.199:6112;
17:50:50.55   Transport::OpenInternal request to WINaddr:192.168.0.199:6112;
17:50:50.55   Session::Host sid = 90F7E02, hostURL = , local addresses = WINaddr:192.168.0.199:6112;
17:50:50.55   ValidateCustomData: called with 463 bytes of custom data
17:50:50.55   Host accepted Peer 1 into the match at address list=WINaddr:192.168.0.199:6112;, routes=WINaddr:192.168.0.199:6112;
17:50:50.55   AdvertisementInternal::Process - EVENT_NEWPEER
17:50:50.55   Session::GetState - info, session's state changed to [2:STATE_CONNECTING].
17:50:50.56   Session::GetState - info, session's state changed to [3:STATE_CONNECTED].
17:50:50.56   hosting - Session is connected
17:50:50.56   Net::Session::SetVisible - session is set to INVISIBLE.
17:50:50.57   hosting completed successfully
17:50:50.57   HostAsync - completed with HostResult = 0
17:50:50.57   UIFrontEnd::StartRelicOnlineTabs deactivating FE_mm_01
17:50:50.57   Activating screen: OnlineGameSetup
17:50:50.57   MessageInternal::CreateChannel: Created channel 534d5347
17:50:50.57   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:50:50.57   MatchInternal::SetMatchType - new type 14 - updating server
17:50:50.58   SetVisible called while !IsConnected
17:50:50.58   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:50:50.58   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:50:50.58   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
17:50:50.58   Activating screen: RelicOnlineChat
17:50:50.58   Activating screen: RelicOnlineNewsScreen
17:50:50.64   Activating screen: RelicOnlineStatsScreen
17:50:50.64   Activating screen: Achievements
17:50:50.64   GAME -- Setting campaign state to 'coh'
17:50:50.64   GAME -- Closing state 'coh'
17:50:50.65   GAME -- Setting campaign state to 'cxp2'
17:50:50.65   GAME -- Closing state 'cxp2'
17:50:50.65   GAME -- Setting campaign state to 'cxp1'
17:50:50.65   GAME -- Closing state 'cxp1'
17:50:50.66   GAME -- Setting campaign state to 'dlc1'
17:50:50.66   GAME -- Closing state 'dlc1'
17:50:50.66   GAME -- Setting campaign state to 'dlc2'
17:50:50.66   GAME -- Closing state 'dlc2'
17:50:50.67   GAME -- Setting campaign state to 'dlc3'
17:50:50.67   GAME -- Closing state 'dlc3'
17:50:50.67   GAME -- Setting campaign state to 'dlc1'
17:50:50.67   GAME -- Closing state 'dlc1'
17:50:50.68   Activating screen: GameHistory
17:50:50.69   GAME -- Setting campaign state to 'coh'
17:50:50.69   GAME -- Closing state 'coh'
17:50:50.69   GAME -- Setting campaign state to 'cxp2'
17:50:50.69   GAME -- Closing state 'cxp2'
17:50:50.70   GAME -- Setting campaign state to 'cxp1'
17:50:50.70   GAME -- Closing state 'cxp1'
17:50:50.71   GAME -- Setting campaign state to 'dlc1'
17:50:50.71   GAME -- Closing state 'dlc1'
17:50:50.71   GAME -- Setting campaign state to 'dlc2'
17:50:50.71   GAME -- Closing state 'dlc2'
17:50:50.72   GAME -- Setting campaign state to 'dlc3'
17:50:50.72   GAME -- Closing state 'dlc3'
17:50:50.72   Activating screen: OnlineGameSetup
17:50:50.72   Activating screen: RelicOnlineTabs
17:50:50.72   AutomatchInternal::OnHostComplete - Completed Host with success=1
17:50:50.72   AutomatchInternal::OnHostComplete - automatcher is no longer active - ignoring
17:50:50.72   QuickMatchInternal::OnHostComplete - Quickmatch not in host state.
17:50:50.73   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:50:50.73   Net::Session::SetVisible - session is set to INVISIBLE.
17:50:50.73   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:50:50.83   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:50:50.85   GAME -- Setting campaign state to 'coh'
17:50:50.85   GAME -- Closing state 'coh'
17:50:50.85   GAME -- Setting campaign state to 'cxp2'
17:50:50.85   GAME -- Closing state 'cxp2'
17:50:50.86   GAME -- Setting campaign state to 'cxp1'
17:50:50.86   GAME -- Closing state 'cxp1'
17:50:50.86   GAME -- Setting campaign state to 'dlc1'
17:50:50.86   GAME -- Closing state 'dlc1'
17:50:50.87   GAME -- Setting campaign state to 'dlc2'
17:50:50.87   GAME -- Closing state 'dlc2'
17:50:50.88   GAME -- Setting campaign state to 'dlc3'
17:50:50.88   GAME -- Closing state 'dlc3'
17:50:50.89   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
17:50:50.94   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:50:51.69   Requesting Relic Downloader soft throttle call [GetNewsAsync] has time 806
17:50:51.69   Sent message game CompanyOfHeroes softthrottle
17:50:51.70   Got dlman msg [ack game CompanyOfHeroes softthrottle]
17:50:52.42   GetUserStats requested stats for PIDs ( 3055263 3060096 ) (best:2, full:0)
17:50:52.42   QueryMatches: Got [41] maps, [127] ids, [17] advertisements, startID [1]
17:50:52.42   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 110 matches
17:50:55.67   QueryMatches: Got [41] maps, [128] ids, [17] advertisements, startID [152008739]
17:50:55.67   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 94 matches
17:50:56.32   Activating screen: DynamicPopupMenu
17:50:59.35   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:50:59.35   Net::Session::SetVisible - session is set to INVISIBLE.
17:50:59.35   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:50:59.36   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:50:59.38   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
17:50:59.40   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:00.07   Activating screen: RaceSelectionPopup
17:51:00.52   QueryMatches: Got [39] maps, [130] ids, [17] advertisements, startID [152009286]
17:51:00.53   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 79 matches
17:51:00.81   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:51:00.81   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:00.81   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:51:00.85   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:00.87   UpdateMatch: Call to UpdateGathering started, matchTypeID = 14.
17:51:00.88   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:01.80   Activating screen: DynamicPopupMenu
17:51:04.44   Transport - Largest sent is now 80
17:51:04.60   Activating screen: RaceSelectionPopup
17:51:05.50   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:51:05.50   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:05.50   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:51:05.52   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:05.54   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:05.58   QueryMatches: Got [40] maps, [131] ids, [17] advertisements, startID [152009683]
17:51:05.58   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 63 matches
17:51:06.89   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:51:06.89   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:06.89   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:51:06.90   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:06.94   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:08.22   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:51:08.22   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:08.22   GetMaxFrameTimeFromProfile: players=4 expected FPS=17.759417, bars=5, max avg=0.055, sd=0.002, 3 samples =  0.06 0.06 0.05
17:51:08.23   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:08.27   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:10.44   QueryMatches: Got [40] maps, [131] ids, [17] advertisements, startID [152009900]
17:51:10.44   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 47 matches
17:51:15.44   QueryMatches: Got [40] maps, [134] ids, [17] advertisements, startID [152010033]
17:51:15.44   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 34 matches
17:51:17.21   GameSetupForm - UpdateMatchType: Setting match type to 14: CLASSIC_COOP_SKIRMISH
17:51:17.21   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:17.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:17.21   MatchInternal::SetMatchState - state 1 - updating server
17:51:17.21   Net::Session::SetVisible - session is set to INVISIBLE.
17:51:17.21   MatchSetup: Sending start game message!
17:51:17.21   GameSetupForm - Starting game
17:51:17.21   GameInfo::ResetInfo - SyncLevel set to 0 on reset
17:51:17.22   PopulateGameInfo - random seed:[1289235077], guid:[{2367ab4c-d1b3-4088-b224-4188a40550ba}], sync level:[0]
17:51:17.22   Error loading [DATA:levelingCurve.lua]
17:51:17.22   Error loading [DATA:levelingCurve.lua]
17:51:17.22   Error loading [DATA:levelingCurve.lua]
17:51:17.23   Error loading [DATA:levelingCurve.lua]
17:51:17.23   MOD - Setting player (0) race to: allies_soviets
17:51:17.23   MOD - Setting player (0) race to: 2
17:51:17.23   MOD - Setting player (1) race to: allies
17:51:17.23   MOD - Setting player (1) race to: 1
17:51:17.23   MOD - Setting player (2) race to: axis
17:51:17.23   MOD - Setting player (2) race to: 3
17:51:17.23   MOD - Setting player (3) race to: axis_panzer_elite
17:51:17.23   MOD - Setting player (3) race to: 4
17:51:17.23   OnlineUpdateStateAsync: initiating state change, id = 152010242, state=2
17:51:17.25   APP -- Game Start
17:51:17.25   Sent message game CompanyOfHeroes allowtraffic
17:51:17.25   GAME -- Setting campaign state to 'coh'
17:51:17.25   GAME -- Closing state 'coh'
17:51:17.26   GAME -- Setting campaign state to 'cxp2'
17:51:17.26   GAME -- Closing state 'cxp2'
17:51:17.26   GAME -- Setting campaign state to 'cxp1'
17:51:17.26   GAME -- Closing state 'cxp1'
17:51:17.27   GAME -- Setting campaign state to 'dlc1'
17:51:17.27   GAME -- Closing state 'dlc1'
17:51:17.27   GAME -- Setting campaign state to 'dlc2'
17:51:17.27   GAME -- Closing state 'dlc2'
17:51:17.28   GAME -- Setting campaign state to 'dlc3'
17:51:17.28   GAME -- Closing state 'dlc3'
17:51:17.28   MessageInternal::DestroyChannel: Destroyed channel 534d5347
17:51:17.29   GAME -- Ending FE
17:51:17.29   UIFrontEnd - Unloading Front End
17:51:17.30   SOUND -- Shutting down ...
17:51:17.36   SOUND -- Shutdown completed!
17:51:17.37
17:51:17.37   GAME -- *** Beginning mission 4p_wolfheze (1 Humans, 3 Computers) ***
17:51:17.37
17:51:17.49   GAME -- Recording game
17:51:17.56   Activating screen: GameLoadScreen
17:51:17.57   Got dlman msg [ack game CompanyOfHeroes allowtraffic]
17:51:17.71   OnlineUpdateStateAsync: updated state for gid=152010242
17:51:17.89   QuazalPostStatsAsync: Simulation results sent to server for gid=0
17:51:17.89   THREAD: Hyper-Threading Technology Processors are not detected.
17:51:17.96   SOUND -- Initializing ...
17:51:18.94   SOUND -- Initialization completed!
17:51:18.98   PHYSICS: detected processor(s) capable of handling 2 threads.
17:51:19.05   MOD -- Locating MOD for scenario 'DATA:scenarios\mp\classic\4p_wolfheze\4p_wolfheze'
17:51:19.05   MOD -- Using Mod 'Eastern_Front'
17:51:19.06   Unable to load/parse precache file [DATA:scenarios\mp\classic\4p_wolfheze\4p_wolfheze_precache.lua]
17:51:19.11   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
17:51:19.12   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
17:51:24.02   GameObjLoader - upgrading load_count from 0 to 1031
17:51:24.52   QueryMatches: Got [41] maps, [130] ids, [17] advertisements, startID [152010130]
17:51:24.52   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 18 matches
17:51:25.00   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.5/0.5, max unsent 0, version err 0, merge 0
17:51:28.11   GameObjLoader - upgrading load_count from 1031 to 1694
17:51:28.40   PHYSICS -- Created node factory 'HVOK'
17:51:28.40   PHYSICS -- Created node factory 'DMMY'
17:51:29.00   QueryMatches: Got [40] maps, [138] ids, [17] advertisements, startID [152010192]
17:51:29.00   QuazalGetAdvertisementsAsync: Queuing refresh to obtain unknown 10 matches
17:51:33.86   QueryMatches: Got [39] maps, [132] ids, [17] advertisements, startID [152010304]
17:51:46.34   GameObjLoader - upgrading load_count from 37 to 3971
17:51:51.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:51: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=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
17:51:52.69   GameObjLoader 0b5600d8 - resetting counters
17:51:52.69   GameObjLoader 0b5600d8 - LOAD_DONE
17:51:52.69   GAME - SessionSetup
17:51:52.97   Re-winding a compressed stream for file 'data:sound\wav\music_nonstream\m06_cityfight_high_load.smf'.  Expensive operation
17:51:53.24   CommandBPDatabase - Unable to register function [splat_attach] due to missing CommandBP.
17:51:53.24   TERRAINTEXTURE -- compositor added RenderTarget [0] of size 2048 x 2048
17:51:53.24   TERRAINTEXTURE -- compositor added RenderTarget [1] of size 1024 x 1024
17:52:00.84   SPDx10 -- Cannot lock non-4 texel aligned regions of DXT textures. xStart: 1600, yStart: 1290
17:52:00.84   SPDx10 -- Could not lock destination texture for copy.
17:52:00.84   SPDx10 -- Cannot lock non-4 texel aligned regions of DXT textures. xStart: 1600, yStart: 1290
17:52:00.84   SPDx10 -- Could not lock destination texture for copy.
17:52:00.84   SPDx10 -- Cannot lock non-4 texel aligned regions of DXT textures. xStart: 1600, yStart: 1290
17:52:00.84   SPDx10 -- Could not lock destination texture for copy.
17:52:00.84   SPDx10 -- Cannot lock non-4 texel aligned regions of DXT textures. xStart: 1600, yStart: 1290
17:52:00.84   SPDx10 -- Could not lock destination texture for copy.
17:52:00.84   SPDx10 -- Cannot lock non-4 texel aligned regions of DXT textures. xStart: 1600, yStart: 1290
17:52:00.84   SPDx10 -- Could not lock destination texture for copy.
17:52:01.08   GAME - CreateGEWorld in 8390 ms
17:52:01.09   TGAIO -- TGA file 'data:simulation/deformdata/Lock_deform.tga' is RLE compressed. For optimal speed, please re-save uncompressed.
17:52:02.52   GAME - SessionSetup finished in 9826 ms
17:52:02.53   GAME - WaterReflectionManagerSetup
17:52:02.53   GAME - WaterReflectionManagerSetup finished in 0 ms
17:52:02.94   MessageInternal::CreateChannel: Created channel 474d4343
17:52:03.17   ModWorld::LoadWinCondition: - [DATA:Scar/WinConditions/zannihilate.scar] succeeded.
17:52:03.17   Sector 1 is a single cell, it can't have its border traced
17:52:05.34   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:52:05.34   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:52:05.34   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:52:05.34   MOD -- Player  (unused player) (frame 0) (KillPlayer)
17:52:06.97   MessageInternal::CreateChannel: Created channel 4d4f444d
17:52:07.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:52:07.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:52:07.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:52:07.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:52:07.83   BindingsSystem -- Cannot create binding.  Unknown type 'repair_radius_circle'
17:52:08.55   SPEECHMANAGER -- Loaded in 0.669676 seconds
17:52:10.57   GameObjLoader - upgrading load_count from 0 to 430
17:52:12.98   GameObjLoader 0b560238 - resetting counters
17:52:12.98   GameObjLoader 0b560238 - LOAD_DONE
17:52:14.28   PreloadResources took 0ms.
17:52:14.41   GAME -- Loading completed (57 seconds)
17:52:14.41   SIM -- Setting SyncErrorChecking level to Low
17:52:17.98   Activating screen: GameScreen
17:52:17.98   Activating screen: Decorators_widescreen
17:52:17.98   Activating screen: Taskbar_widescreen
17:52:17.98   Activating screen: SubtitleScreen
17:52:17.98   Activating screen: TextOverlayScreen
17:52:18.07   PerformanceRecorder::StartRecording for game size 4
17:52:18.07   GAME -- Starting mission...
17:52:18.98   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI)
17:52:18.98   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI)
17:52:18.98   MOD -- Player CPU - Normal set to AI Type: AI Player (frame 1) (CmdAI)
17:52:24.96   Warning: non auto-match upgrade not found in AE, tuning
17:52:25.02   RNT_ReportPing: user 3211621:BOPOHA CANNOT connect pingMS=65535
17:52:26.01   Transport - median kBPS [hi/cur] sent = 0.3/0.1, recvd = 0.3/0.0, #p/sec[s/r] = 0.4/0.2, max unsent 0, version err 0, merge 0
17:52:26.59   Activating screen: Command_Tree
17:52:41.40   Activating screen: Command_Branch
17:52:46.41   Warning: binding repeat_1(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
17:52:52.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:52: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=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
17:53:18.59   Warning: binding repeat_0(Ability: abilities\reenable_capture_ability) -- ui index '0' out of bounds; range is [1, 12]
17:53:23.45   Warning: binding selection bindings_child6() -- Binding selection bindings_child6: failed bind to widget 'build_max_background'
17:53:27.01   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
17:53:53.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:53: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=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
17:54:28.00   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
17:54:54.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:54: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=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
17:55:29.00   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
17:55:55.01   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:55: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=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
17:56:30.01   Transport - median kBPS [hi/cur] sent = 0.3/0.0, recvd = 0.3/0.0, #p/sec[s/r] = 0.0/0.0, max unsent 0, version err 0, merge 0
17:56:34.00   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:46.14   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:46.14   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:46.14   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:46.40   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:46.88   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:54.65   Unable to bind updater for fx [ui\reveal].  It could be looping in a fire-n-forget action.
17:56:56.00   local host PeerID 1 CONN ack=  0 (  0ms~0) unack=  0, retry=  0, highwaterOOS=0 @WINaddr:192.168.0.199:6112; (ping=0ms) 100.00%, pending=0, dead=0
17:56:56.00   MessageCounts: inval=0/0, seek=0/0, join=0/0, integ=0/0, seek_reply=0/0, join_reply=0/0, add=0/0, remove=0/0, drop=0/0, data=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
