AOE4 Mastery progression bugs

Game Version:

  • Build: 7.1.113.0
  • Platform: Steam
  • Operating System: Windows 10
  • Gamertag: Brontide

Issue:

English Mastery: Test of Strength 1 not progressing.

Reproduction Steps:

I launch a 1v1 Skirmish against Easy AI as English and upon completion the mastery progress is not given. I have verified local files, reset router, restarted computer, and beaten the easy AI several times.

ATTACHMENTS: Game Start will be in bold

(I) [14:44:34.836] [000013804]: starting local hosting
(I) [14:44:34.836] [000013804]: ValidateCustomData: called with 2549 bytes of custom data
(I) [14:44:34.836] [000013804]: WorldwideAdvertisementService::Process - EVENT_NEWPEER
(I) [14:44:34.849] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:44:34.856] [000013804]: Sending matchinfo change #2
(I) [14:44:34.856] [000013804]: HostAsync - got operation info 0:SessionOperationInfo::SUCCESS
(I) [14:44:34.879] [000013804]: hosting - Session is connected
(I) [14:44:34.917] [000013804]: HostAsync - completed with HostResult = 0
(I) [14:44:34.918] [000013804]: WorldwidePartyService::SetMatchState - state 0 unchanged ignoring
(I) [14:44:34.922] [000013804]: WorldwideAutomatchService::OnHostComplete - automatcher is no longer active - stopPollPending 0 automatchPollID 18446744073709551615
(I) [14:44:34.938] [000013804]: UI Async loading data:ui\lobby\semimodalpages\CreateCustomMatchSemiModalPage.xaml
(I) [14:45:35.005] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:45:36.936] [000013804]: [Match Flow] Start Match Command
(I) [14:45:36.937] [000013804]: [Match Flow] Countdown started.
(I) [14:45:38.951] [000013804]: [Match Flow] Countdown Complete
(I) [14:45:38.952] [000013804]: [Match Flow] MatchSetup::StartMatch() - m_matchStatus = ST_STARTING
(I) [14:45:39.257] [000013804]: UI Async loading data:ui\loading\pages\loadmultiplayerpage.xaml
(I) [14:45:39.395] [000013804]: Sending matchinfo change #61
(I) [14:45:39.417] [000013804]: WorldwidePartyService::SetMatchState - state 1 not an online game, no server call necessary
(I) [14:45:39.530] [000013804]: Sending matchinfo change #62
(I) [14:45:39.530] [000013804]: WorldwideAutomatchService::OnStartComplete - detected successful game start
(I) [14:45:39.530] [000013804]: Match Started - [11845606 /steam/76561198044142121], slot = 0, ranking = -1
(I) [14:45:42.433] [000013804]: Match key created, UID=524257810
(I) [14:45:42.442] [000013804]: MatchSetup::OnCreateSinglePlayerEvent
(I) [14:45:42.450] [000013804]: Sending matchinfo change #63
(I) [14:45:42.450] [000013804]: [Match Flow] MatchSetupManager::Update() - MatchSetup::ST_LOADING
(I) [14:45:42.450] [000013804]: [Match Flow] GameApp::RequestGameBegin()
(I) [14:45:42.467] [000013804]: MOD - Setting player (0) race to: 106553
(I) [14:45:42.467] [000013804]: MOD - Setting player (1) race to: 106553
(I) [14:45:42.467] [000013804]: ModDllSetup: SetStatsGameUID=524257810
(I) [14:45:42.467] [000013804]: GAME – Scenario:
(I) [14:45:42.467] [000013804]: GAME – Win Condition Qualified Name: 00000000000000000000000000000000:149419911
(I) [14:45:42.467] [000013804]: GAME – Win Condition Name: standard_mode
(I) [14:45:42.467] [000013804]: GAME – Human Player: 0 Brontide 11845606 10002 english
(I) [14:45:42.467] [000013804]: GAME – AI Player: 1 A.I. Easy -1 10002 english
(I) [14:45:43.317] [000013804]: GameObj::StartGameObj - info, network session GUID set to [524257810].
(I) [14:45:43.318] [000002200]: Loading step: [OnBeginLoad]
(I) [14:45:43.318] [000002200]: Loading step: [Assign Players]
(I) [14:45:43.318] [000002200]: [Match Flow] GameWorld::AssignPlayers()
(I) [14:45:43.319] [000002200]: Loading step: [FXReflection]
(I) [14:45:43.319] [000002200]: Loading step: [FXDataContext]
(I) [14:45:43.319] [000002200]: Loading step: [FX Texture Pack]
(I) [14:45:43.320] [000002200]: Loading step: [Run Havok Garbage Collection]
(I) [14:45:43.320] [000002200]: Loading step: [Flush Inventory On Application Exit]
(I) [14:45:43.320] [000002200]: Loading step: [Default World]
(I) [14:45:43.630] [000002200]: sucessfully loaded art\civilizations\common\weapons\projectiles\common_arrow\common_arrow
(I) [14:45:43.646] [000002200]: sucessfully loaded art\civilizations\common\weapons\projectiles\common_bolt\common_bolt
(I) [14:45:43.646] [000002200]: Loading step: [FX Command Function]
(I) [14:45:43.646] [000002200]: Loading step: [AnimatorCommandFunction]
(I) [14:45:43.646] [000002200]: Loading step: [MemShrink]
(I) [14:45:43.647] [000002200]: Loading step: [Sync Checking]
(I) [14:45:43.647] [000002200]: Loading step: [Mod Packs]
(I) [14:45:43.647] [000002200]: Loading step: [SimVis System]
(I) [14:45:43.647] [000002200]: Loading step: [DefaultWorld]
(I) [14:45:43.647] [000002200]: Loading step: [Visual Physics ME]
(I) [14:45:43.647] [000002200]: Loading step: [Deferred Decal Manager]
(I) [14:45:43.647] [000002200]: Loading step: [FogVolumeManager]
(I) [14:45:43.647] [000002200]: Loading step: [Vehicle Physics Function]
(I) [14:45:43.647] [000002200]: Loading step: [Unit Occlusion Function]
(I) [14:45:43.647] [000002200]: Loading step: [Splat Function]
(I) [14:45:43.647] [000002200]: Loading step: [Grass Function]
(I) [14:45:43.647] [000002200]: Loading step: [Object Alpha Factor Function]
(I) [14:45:43.647] [000002200]: Loading step: [Renderable Managers]
(I) [14:45:43.647] [000002200]: Loading step: [Setup Skins]
(I) [14:45:43.647] [000002200]: FILESYSTEM – filepath failure, missing alias ‘.bin’
(I) [14:45:43.647] [000002200]: Loading step: [AnimEventSetup]
(I) [14:45:43.647] [000002200]: Loading step: [Session Precache]
(I) [14:45:43.647] [000002200]: Loading step: [Precache core resources]
(I) [14:45:43.656] [000002200]: Loading step: [Precache EBPs]
(I) [14:45:43.656] [000002200]: FILESYSTEM – filepath failure, missing alias ‘.bin’
(I) [14:45:44.046] [000002200]: Loading step: [Precache State Tree references]
(I) [14:45:44.048] [000002200]: Loading step: [Load Actions]
(I) [14:45:44.049] [000002200]: Loading step: [Load Resources from Precache]
(I) [14:45:44.051] [000002200]: GameObjLoader - upgrading load_count from 0 to 1775
(I) [14:45:44.051] [000002200]: Loading step: [Load Resources from Precache]
(I) [14:45:44.525] [000002200]: Loading step: [Load Resources from Precache]
(I) [14:45:44.924] [000002200]: GameObjLoader - upgrading load_count from 1775 to 1793
(I) [14:45:56.124] [000002200]: GameObjLoader - upgrading load_count from 1636 to 1637
(I) [14:45:56.420] [000002200]: GameObjLoader - upgrading load_count from 1637 to 1639
(I) [14:46:20.851] [000002200]: GameObjLoader 0x0000012992924FC0 - resetting counters
(I) [14:46:20.851] [000002200]: GameObjLoader 0x0000012992924FC0 - LOAD_DONE
(I) [14:46:20.851] [000002200]: Loading step: [GEWorld]
(I) [14:46:20.851] [000002200]: GAME - InitializeGEWorld
(I) [14:46:23.735] [000002200]: SIM – Setting SyncErrorChecking level to None
(I) [14:46:23.735] [000002200]: MapGen – Below are the parameters used during map generation
(I) [14:46:23.735] [000002200]: MapGen – If you have feedback about the map, send this text to design so that they can reproduce it
(I) [14:46:23.735] [000002200]: MapGen – Generating with biome coastal_western
(I) [14:46:23.735] [000002200]: MapGen – Generating with layout volcanic_island
(I) [14:46:23.735] [000002200]: MapGen – Generating with size map_size_416
(I) [14:46:23.735] [000002200]: MapGen – Generating with seed 377785705
(I) [14:46:23.735] [000002200]: MapGen – Generating with player count 2
(I) [14:46:25.871] [000002200]: Regenerating ImpassMap data…
(I) [14:46:25.909] [000002200]: Regenerating SimTerrainCoverMap data…
(I) [14:46:25.965] [000002200]: SimTerrainCoverMap generation took 0.055175 seconds.
(I) [14:46:25.965] [000002200]: Pathfinding::Regenerate()…
(I) [14:46:26.076] [000002200]: Pathfinding::Regenerate() Done.
(I) [14:46:29.997] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:30.000] [000002200]: GameObjLoader - upgrading load_count from 0 to 37
(I) [14:46:30.000] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:30.571] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:31.495] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:32.103] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:32.630] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:33.734] [000002200]: Loading step: [Load Resources from GEWorld]
(I) [14:46:34.083] [000002200]: GameObjLoader 0x0000012992924EE0 - resetting counters
(I) [14:46:34.083] [000002200]: GameObjLoader 0x0000012992924EE0 - LOAD_DONE
(I) [14:46:34.083] [000002200]: Loading step: [CommandDB]
(I) [14:46:34.083] [000002200]: Loading step: [Sound Banks]
(I) [14:46:34.083] [000002200]: Loading step: [Session]
(I) [14:46:34.084] [000002200]: GAME - SessionSetup
(I) [14:46:34.085] [000002200]: GAME - SessionSetup finished in 1 ms
(I) [14:46:34.085] [000002200]: Loading step: [Player Setup]
(I) [14:46:34.086] [000002200]: GAME – Recording game
(I) [14:46:34.086] [000002200]: Loading step: [Scenario Lua System]
(I) [14:46:34.109] [000002200]: FILESYSTEM – filepath failure, missing alias ‘.prefabdata’
(I) [14:46:34.109] [000002200]: FILESYSTEM – filepath failure, missing alias ‘*.prefabdata’
(I) [14:46:34.109] [000002200]: Loading step: [Team Colour Init]
(I) [14:46:34.109] [000002200]: Loading step: [Race Precaching Event Listener Registration]
(I) [14:46:34.109] [000002200]: Loading step: [Simulation]
(I) [14:46:34.755] [000002200]: LoadWinCondition: - [GameModes/standard_mode] succeeded.
(I) [14:46:34.950] [000002200]: Loading step: [GameUICore System]
(I) [14:46:34.955] [000002200]: Loading step: [UI System]
(I) [14:46:34.955] [000002200]: Loading step: [LUA]
(I) [14:46:34.955] [000002200]: Loading step: [Game Event Listener Registration]
(I) [14:46:34.955] [000002200]: Loading step: [CPU AI]
(I) [14:46:34.955] [000002004]: Registered essence thread: [AI_Thread] usesRcssWorkerThreads: [true]
(I) [14:46:35.093] [000002200]: Loading step: [Scar Init]
(I) [14:46:35.109] [000002200]: Loading step: [FX System]
(I) [14:46:35.109] [000002200]: Loading step: [Cheat Menu]
(I) [14:46:35.114] [000002200]: Loading step: [Load resources]
(I) [14:46:35.115] [000002200]: GameObjLoader - upgrading load_count from 0 to 8
(I) [14:46:35.115] [000002200]: Loading step: [Load resources]
(I) [14:46:35.116] [000002200]: Loading step: [Load resources]
(I) [14:46:35.134] [000002200]: GameObjLoader 0x0000012992924DC0 - resetting counters
(I) [14:46:35.134] [000002200]: GameObjLoader 0x0000012992924DC0 - LOAD_DONE
(I) [14:46:35.134] [000002200]: Loading step: [PreDisplay]
(I) [14:46:35.134] [000002200]: Loading step: [Free Loading Data]
(I) [14:46:35.134] [000002200]: PreloadResources took 0ms.
(I) [14:46:35.134] [000002200]: Loading step: [MemShrink]
(I) [14:46:35.134] [000002200]: Loading step: [Flush Inventory]
(I) [14:46:35.138] [000002200]: Loading step: [Resolve Impasse Blockers]
(I) [14:46:35.138] [000002200]: Loading step: [DefaultWorld Begin Play]
(I) [14:46:35.175] [000002200]: Loading step: [FrontEnd simulation initialization]
(I) [14:46:35.175] [000002200]: Loading step: [WPFGFrontEnd loading]
(I) [14:46:35.175] [000002200]: Loading step: [WPFGFrontEnd loading]
(I) [14:46:35.175] [000002200]: Loading step: [WPFGFrontEnd loading]
(I) [14:46:35.236] [000013804]: UI Async loading data:ui\hud\pages\cardinalhudpage.xaml
(I) [14:46:35.236] [000002200]: Loading step: [WPFGFrontEnd loading]
(I) [14:46:36.009] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:46:37.797] [000013804]: WPFGFrontEnd::UnblockLoading
(I) [14:46:37.797] [000002200]: Loading step: [OnEndLoad]
(I) [14:46:45.189] [000013804]: PerformanceRecorder::StartRecording for game size 2
(I) [14:46:45.189] [000013804]: GAME – Starting mission:
(I) [14:46:45.189] [000013804]: MEM – available page 16918 mb, total page 31664 mb
(I) [14:46:45.223] [000010844]: RENDERING - new resize requested [512, 512] reason [0]
(I) [14:46:45.224] [000010844]: RENDERING - Compositor resize requested via resize event
(I) [14:46:45.224] [000010844]: RENDERING - Starting compositor resize to [512 x 512] for reason 0
(I) [14:46:45.226] [000010844]: RENDERING - Finished compositor resize to [512 x 512] for reason 0
(I) [14:46:45.298] [000010844]: RENDERING - new resize requested [512, 512] reason [0]
(I) [14:46:45.298] [000010844]: RENDERING - Compositor resize requested via resize event
(I) [14:46:45.298] [000010844]: RENDERING - Starting compositor resize to [512 x 512] for reason 0
(I) [14:46:45.300] [000010844]: RENDERING - Finished compositor resize to [512 x 512] for reason 0
(I) [14:46:45.467] [000013804]: MOD – Player A.I. Easy set to AI Type: AI Player (frame 1) (CmdAI)
(I) [14:47:37.009] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:48:38.003] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:49:39.008] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:50:40.007] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:51:41.000] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:52:40.175] [000013804]: World_KillPlayer - Player [A] killed on game tick [2833] due to [7]
(I) [14:52:40.175] [000013804]: Player [A.I. Easy] killed on game tick [2833] due to [loss]
(I) [14:52:42.003] [000015140]: MessageCounts: inval=0/0, seek=0/0, join=0/0, ichk=0/0, sk_r=0/0, deny=0/0, Padd=0/0, Pdel=0/0, drop=0/0, data=0/0, mdat=0/0, voip=0/0, rchk=0/0, nudg=0/0, Thdr=0/0, Peer=0/0, PPrx=0/0, ping=0/0, Pfrg=0/0, rely=0/0, lb_p=0/0, lb_c=0/0, cast=0/0, cdat=0/0, sorq1=0/0, sorp=0/0, acto=0/0, spob=0/0, sorq2=0/0, setp=0/0, actr=0/0, lsim=0/0, rlbc=0/0, rlbc2=0/0, endg=0/0
(I) [14:53:00.200] [000013804]: MOD – Game Over at frame 2993
(I) [14:53:00.230] [000013804]: Sending single player match result to server, reporting XP gain of [220]
(I) [14:53:01.011] [000013804]: Match report sent
(I) [14:53:01.138] [000011820]: Read bytes [0,“AvatarUpdateMessage”,11845606,[[1999,11845606,“/steam/76561198044142121”,“{"sharedHistory":1,"hardwareType":0,"inputDeviceType":-1}”,“Brontide”,“”,4463066,31709,25,2074390,null,“76561198044142121”,3,[]]]]
(I) [14:53:01.138] [000011820]: Read bytes [0,“OfflineMessageMessage”,11845606,[“108308554”,“11845606”,[0,2,“system”,“”,“”,“”,2,0,1,-1,null,“”,4,[]],“SYSTEM_OFFLINEITEM_LEVEL”,“War Spoils Obtained”,“Here are your items recently obtained through War Spoils.”,1691693581,“”,[[108296306,2,11845606,0,0,1,null,null],[[“11845606”,[[930942869,2,451949,11845606,1,0,“”,1691693581,-1,108296306,35,-1]]]]]]]
(I) [14:53:01.138] [000011820]: Read bytes [0,“AvatarStatsUpdatedMessage”,11845606,[[[1,11845606,44,“”,1691693581]]]]
(I) [14:53:01.138] [000011820]: Read bytes [0,“GameResultNotificationMessage”,11845606,[[[11845606,52,106553,-1,[-1],4463066,[[“unitprod”,118],[“vvetrank”,0],[“cabil”,0],[“dmgdone”,0],[“plost”,0],[“svetrank”,0],[“reqmax”,0],[“cpearn”,0],[“reqspnt”,0],[“powearn”,0],[“blost”,0],[“elitekill”,6],[“edeaths”,3],[“structdmg”,0],[“pcap”,0],[“inactperiod”,25],[“lowintperiod”,0],[“precap”,0],[“sqkill”,6],[“popmax”,0],[“powspnt”,0],[“sqprod”,113],[“bprod”,49],[“svetxp”,0],[“vabnd”,0],[“addonkill”,0],[“totalcmds”,463],[“gammaspnt”,0],[“vkill”,0],[“objdmh”,0],[“abil”,4],[“sqlost”,3],[“vcap”,0],[“vlost”,0],[“gt”,374],[“upg”,14],[“vvetxp”,0],[“reqearn”,0],[“vp1”,0],[“vp0”,0],[“erein”,0],[“cflags”,0],[“wpnpu”,0],[“ekills”,6],[“powmax”,0],[“vprod”,0]],0,220,[],null,[],[],[],[],[],[]]],[[4463066,49,17,0,17,0,0,-1,-1,-1,-1,-1,1000,1691693581,0,0,1000]],[[1,11845606,44,“”,1691692047]],131064452,“”,[[59,[],[]],[60,[],[]]]]]
(I) [14:53:01.144] [000013804]: RNT_StatsUpdate: Win notification, profileID 11845606, race =106553, level=-1, ranking=-1
(I) [14:53:03.796] [000013804]: UI Async loading data:ui\lobby\modalpages\PostGameMatchDetailsModalPage.xaml
(I) [14:53:04.001] [000013804]: UI Async loading data:ui\Lobby\Pages\PostGameProgressionPage.xaml
(I) [14:53:04.361] [000013804]: GetPartyStatsByID found 1 teams for user ID 11845606
(I) [14:53:08.914] [000013804]: UI Async loading data:ui\Lobby\Pages\PostGameResultsPage.xaml
(I) [14:53:09.946] [000013804]: UI Async loading data:ui\Lobby\Pages\PostGameTimelinePage.xaml
(I) [14:53:10.831] [000013804]: UI Async loading data:ui\shared\pages\statspage.xaml
(I) [14:53:11.429] [000013804]: UI Async loading data:ui\Lobby\Pages\PostGameProgressionPage.xaml
(I) [14:53:16.231] [000013804]: GameObj::ShutdownGameObj
(I) [14:53:16.232] [000013804]: GetMaxFrameTimeFromProfile: players=2 expected FPS=100.000000, bars=0, max avg=0.000, sd=0.000, 0 samples =
(I) [14:53:16.232] [000013804]: PerformanceRecorder::EndRecording - game size=2, max average=0.019668, worst frame=0.010000
(I) [14:53:16.232] [000013804]: Recording: No [2 players]

(I) [14:53:16.232] [000013804]: Max/Avg: 0.02, 0.02 sec (fps=50.84, 58.77) (19 samples)

(I) [14:53:16.232] [000013804]: Bars: 0

(I) [14:53:16.232] [000013804]: GameObj – RLink::NetworkManager::Get()->GetAchievementInterface().DeltaPlatformStat() failed. statName[mp_seconds_played]
(I) [14:53:16.232] [000013804]: GameObj – RLink::NetworkManager::Get()->GetAchievementInterface().DeltaPlatformStat() failed. statName[mp_units_produced]
(I) [14:53:16.232] [000013804]: GameObj – RLink::NetworkManager::Get()->GetAchievementInterface().DeltaPlatformStat() failed. statName[mp_units_killed]
(I) [14:53:16.233] [000013804]: GameObj – RLink::NetworkManager::Get()->GetAchievementInterface().DeltaPlatformStat() failed. statName[mp_units_lost]
(I) [14:53:16.234] [000013804]: Unloading step: [OnEndLoad]
(I) [14:53:16.234] [000013804]: Unloading step: [WPFGFrontEnd loading]
(I) [14:53:16.234] [000013804]: Unloading step: [FrontEnd simulation initialization]
(I) [14:53:16.234] [000013804]: Unloading step: [DefaultWorld Begin Play]
(I) [14:53:16.234] [000013804]: Unloading step: [Resolve Impasse Blockers]
(I) [14:53:16.234] [000013804]: Unloading step: [Flush Inventory]
(I) [14:53:16.234] [000013804]: Unloading step: [MemShrink]
(I) [14:53:16.234] [000013804]: Unloading step: [Free Loading Data]
(I) [14:53:16.234] [000013804]: Unloading step: [PreDisplay]
(I) [14:53:16.234] [000013804]: Unloading step: [Load resources]
(I) [14:53:16.234] [000013804]: GameObjLoader 0x0000012992924DC0 - Unload
(I) [14:53:16.234] [000013804]: GameObjLoader 0x0000012992924DC0 - resetting counters
(I) [14:53:16.234] [000013804]: Unloading step: [Cheat Menu]
(I) [14:53:16.236] [000013804]: Unloading step: [FX System]
(I) [14:53:16.236] [000013804]: Unloading step: [Scar Init]
(I) [14:53:16.236] [000013804]: Unloading step: [CPU AI]
(I) [14:53:16.244] [000013804]: Unloading step: [Game Event Listener Registration]
(I) [14:53:16.244] [000013804]: Unloading step: [LUA]
(I) [14:53:16.244] [000013804]: Unloading step: [UI System]
(I) [14:53:16.244] [000013804]: Unloading step: [GameUICore System]
(I) [14:53:16.246] [000013804]: Unloading step: [Simulation]
(I) [14:53:16.246] [000013804]: StatArtWarningsCount 0
(I) [14:53:16.246] [000013804]: StatDataWarningsCount 0
(I) [14:53:16.246] [000013804]: Unloading step: [Race Precaching Event Listener Registration]
(I) [14:53:16.246] [000013804]: Unloading step: [Team Colour Init]
(I) [14:53:16.246] [000013804]: Unloading step: [Scenario Lua System]
(I) [14:53:16.246] [000013804]: Unloading step: [Player Setup]
(I) [14:53:16.246] [000013804]: Unloading step: [Session]
(I) [14:53:16.246] [000013804]: Unloading step: [Sound Banks]
(I) [14:53:16.246] [000013804]: Unloading step: [CommandDB]
(I) [14:53:16.246] [000013804]: Unloading step: [Load Resources from GEWorld]
(I) [14:53:16.246] [000013804]: GameObjLoader 0x0000012992924EE0 - Unload
(I) [14:53:16.246] [000013804]: GameObjLoader 0x0000012992924EE0 - resetting counters
(I) [14:53:16.246] [000013804]: Unloading step: [GEWorld]
(I) [14:53:16.423] [000013804]: Unloading step: [Load Resources from Precache]
(I) [14:53:16.423] [000013804]: GameObjLoader 0x0000012992924FC0 - Unload
(I) [14:53:16.423] [000013804]: GameObjLoader 0x0000012992924FC0 - resetting counters
(I) [14:53:16.423] [000013804]: Unloading step: [Load Actions]
(I) [14:53:16.423] [000013804]: Unloading step: [Precache State Tree references]
(I) [14:53:16.423] [000013804]: Unloading step: [Precache EBPs]
(I) [14:53:16.426] [000013804]: Unloading step: [Precache core resources]
(I) [14:53:16.426] [000013804]: Unloading step: [Session Precache]
(I) [14:53:16.426] [000013804]: Unloading step: [AnimEventSetup]
(I) [14:53:16.426] [000013804]: Unloading step: [Setup Skins]
(I) [14:53:16.426] [000013804]: Unloading step: [Renderable Managers]
(I) [14:53:16.426] [000013804]: Unloading step: [SimVis System]
(I) [14:53:16.428] [000013804]: Unloading step: [Object Alpha Factor Function]
(I) [14:53:16.428] [000013804]: Unloading step: [Grass Function]
(I) [14:53:16.428] [000013804]: Unloading step: [Splat Function]
(I) [14:53:16.428] [000013804]: Unloading step: [Unit Occlusion Function]
(I) [14:53:16.428] [000013804]: Unloading step: [Vehicle Physics Function]
(I) [14:53:16.428] [000013804]: Unloading step: [FogVolumeManager]
(I) [14:53:16.428] [000013804]: Unloading step: [Deferred Decal Manager]
(I) [14:53:16.429] [000013804]: Unloading step: [Visual Physics ME]
(I) [14:53:16.429] [000013804]: Unloading step: [DefaultWorld]
(I) [14:53:16.429] [000013804]: Unloading step: [Mod Packs]
(I) [14:53:16.430] [000013804]: Unloading step: [Sync Checking]
(I) [14:53:16.430] [000013804]: Unloading step: [MemShrink]
(I) [14:53:16.430] [000013804]: Unloading step: [AnimatorCommandFunction]
(I) [14:53:16.430] [000013804]: Unloading step: [FX Command Function]
(I) [14:53:16.430] [000013804]: Unloading step: [Default World]
(I) [14:53:16.523] [000013804]: Unloading step: [Flush Inventory On Application Exit]
(I) [14:53:16.523] [000013804]: Unloading step: [Run Havok Garbage Collection]
(I) [14:53:16.547] [000013804]: Unloading step: [FX Texture Pack]
(I) [14:53:16.548] [000013804]: Unloading step: [FXDataContext]
(I) [14:53:16.548] [000013804]: Unloading step: [FXReflection]
(I) [14:53:16.548] [000013804]: Unloading step: [Assign Players]
(I) [14:53:16.548] [000013804]: Unloading step: [OnBeginLoad]
(I) [14:53:16.578] [000013804]: SessionID : fffffffffffffffe - Disconnect called with reasonID 1000 - MatchSetup::Disconnect
(I) [14:53:16.579] [000013804]: SessionID : fffffffffffffffe - Disconnect called with reasonID 1000 - MatchSetup::Disconnect
(I) [14:53:16.718] [000013804]: UI Async loading data:ui\Lobby\Widgets\TaskBarWidget.xaml
(I) [14:53:16.718] [000013804]: UI Async loading data:ui\lobby\widgets\notificationwidget.xaml
(I) [14:53:16.718] [000013804]: UI Async loading data:ui\Lobby\Pages\FEBackgroundPage.xaml
(I) [14:53:16.720] [000013804]: Disconnect process already running
(E) [14:53:16.759] [000013804]: SetVisible called while !IsConnected
(I) [14:53:16.776] [000013804]: UI Async loading data:ui\Lobby\Pages\SinglePlayerPage.xaml
(I) [14:53:16.821] [000013804]: WorldwideAdvertisementService::Process - EVENT_DESTROYPEER
(I) [14:53:16.821] [000013804]: WorldwidePartyService::PeerRemove, Removing peer ID: 1
(I) [14:53:16.821] [000013804]: peerremove - peerIDRemoved=11845606, reasonID=1000, reason debug hint - MatchSetup::Disconnect
(I) [14:53:16.846] [000013804]: PeerRemoveAll - flushing local session peer data
(I) [14:53:16.846] [000013804]: LeaveSessionInternal - no action taken, needLeave=0, service=2
(I) [14:53:16.846] [000013804]: Destroyed Matchinfo for sessionID -2
(I) [14:53:16.847] [000013804]: OnDestroyPartyNotification - partyID = -2, prevID = -1
(I) [14:53:16.847] [000013804]: OnDestroyPartyNotification - partyID = -2, prevID = -1
(I) [14:53:16.921] [000013804]: UI Async loading data:ui\Lobby\Pages\SinglePlayerPageCampaignPane.xaml
(I) [14:53:16.930] [000013804]: UI Async loading data:ui\Lobby\Pages\SinglePlayerPageSkirmishPane.xaml
(I) [14:53:21.430] [000013804]: UI Async loading data:ui\lobby\modalpages\ChallengePathsModalPage.xaml
(I) [14:54:01.776] [000013804]: UI Async loading data:ui\shared\modalpages\SettingsModalPage.xaml
(I) [14:54:01.783] [000013804]: UI Async loading data:ui\shared\modalpages\SettingsModalPageControlsPane.xaml
(I) [14:54:01.791] [000013804]: UI Async loading data:ui\shared\modalpages\HotKeyRemapModalPage.xaml
(I) [14:54:01.891] [000013804]: UI Async loading data:ui\shared\modalpages\HotKeyRemapCommonKeysTabPage.xaml
(I) [14:54:10.896] [000013804]: UI Async loading data:ui\shared\modalpages\SystemConfigGenericPane.xaml
(I) [14:54:14.878] [000013804]: UI Async loading data:ui\lobby\modalpages\ChallengePathsModalPage.xaml

I am experiencing other mastery related bugs like the file name for several civs is showing up instead of the title of the mastery challenge EX: mastery_chinese_03 instead of Test of Strength 1. Unsure if related but that particular bug is not present on the English Mastery Tree.

I have so far tested French, HRE, Rus, China, Mongol, Abbasid, and I am testing Delhi, all will progress mastery until the first Test of Strength and then will refuse to advance further.

I am having this issue with every single Civ. I have completed each and every one of the first two objectives. (Including malians, had to do the scenario in art of war to get it) and NONE of them are advancing when I beat an Easy AI.

Okay, Not sure what happened. I tried changing from no teams to random teams and nothing happened but when I went random everything, civs, teams, colors etc. I got both Abbassid and English to show up. Gunna do some more experimenting. Maybe the random Teams is what does it.

Moved to correct sub forum.

So there are some display bugs with masteries, but I have been able to complete most of them just fine on the latest patch. Just a quick check…When I do the test of strength masteries, they do complete…BUT I do NOT get the notification at the end-of-game xp screen, and NEITHER do I get that red icon down at the bottom of the screen telling me I completed a mastery. Are you assuming that the mastery didn’t complete because you received neither notice? That is unfortunately an old bug. Can you go into the mastery screen and verify that the test of strength is not complete there? If you have just been doing the test games and thinking you will get a notice like the other masteries in the post-game xp screen, you will not get one at this current time. If the mastery is not complete on the actual mastery screen then that is something weird, as I have only started doing masteries myself in Season 5 patch, and everyone has worked as expected so far.

Until about 4am last night it did not show up on the mastery screen. I had tried a 1v1 with every civ a few times with different win conditions and maps and nothing worked. Then I read a post for a Test of Strength II and it said that when they swapped from No Team to Random Team during setup they got it. I tried my luck and nothing seemed to happen. I then chose all random. Random civs, random teams, random color etc. It gave me abbassid and while the end of game screen did not display I did get red notifications for both English and Ab. I am going to run a China right now with random teams and see if progress is made there. If so I will post the solution to my problem.

Confirmed. Regardless of the string error with mastery_chinese_03 and it not appearing in the endgame summary screen upon returning to the menu a red Notification appeared and the mastery was completed.

The Fix that worked: use RANDOM TEAM not NO TEAM during the setup. Hope this helps someone!