15:40:01.425 WARN 18492 src\lib.rs:162 proxy proxy from 2026-03-07T16:01:57.013974Z is initializing
15:40:01.425 INFO 18492 src\lib.rs:231 proxy Rust data: 0x5b5a6000 .. 0x5b5b1574
15:40:01.425 INFO 18492 src\lib.rs:221 proxy Rust const VF tables: 0x5b5b2000 .. 0x5b5b22f4
15:40:01.425 INFO 18492 src\lib.rs:211 proxy Rust VF tables: 0x5b5b3000 .. 0x5b5b41a4
15:40:01.426 INFO 18492 src\patches\debug\not_a_game_talk.rs:19 proxy::patches::debug::not_a_game_talk not a game talk hooks initialized
15:40:01.426 TRACE 18492 src\patches\debug\lua.rs:63 proxy::patches::debug::lua ScriptTraceConfig { trace_lua_calls: false, trace_lua_assignments: false, trace_lua_chunk_parsing: false, map_scripts: MapScriptsConfig { trace_state_transitions: false, trace_all_action_creation: false, trace_before_all_action_call: false, trace_after_all_action_call: false, script_actions: {} }, extra_log_file: None, delete_trace: false, print_to_log: false }
15:40:01.426 INFO 18492 src\patches\debug\lua\script_actions.rs:177 proxy::patches::debug::lua::script_actions Scrip actions initialized
15:40:01.426 INFO 18492 src\patches\debug\lua.rs:105 proxy::patches::debug::lua lua tracing initialized
15:40:01.427 INFO 18492 src\patches\lua_functions.rs:58 proxy::patches::lua_functions lua hooks initialized
15:40:01.427 INFO 18492 src\patches\lua_functions\gds\entity_effect_start.rs:17 proxy::patches::lua_functions::gds::entity_effect_start lua gds entity effect start hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\gds.rs:52 proxy::patches::lua_functions::gds lua gds hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\cpg_card.rs:19 proxy::patches::lua_functions::cpg_card lua card hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\player.rs:22 proxy::patches::lua_functions::player lua player hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\widget\mod.rs:21 proxy::patches::lua_functions::widget lua widget hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\chat_channel.rs:34 proxy::patches::lua_functions::chat_channel chat channel hooks initialized
15:40:01.428 INFO 18492 src\patches\lua_functions\luabind.rs:22 proxy::patches::lua_functions::luabind Luabind hooks initialized
15:40:01.429 INFO 18492 src\patches\bugs\skip_broken_effects.rs:17 proxy::patches::bugs::skip_broken_effects 'bad' effects should not crash
15:40:01.429 INFO 18492 src\patches\bugs\spell_teleport_area.rs:21 proxy::patches::bugs::spell_teleport_area spell teleport area fix initialized
15:40:01.429 INFO 18492 src\patches\bugs\stampede.rs:9 proxy::patches::bugs::stampede Stampede fix initialized
15:40:01.430 TRACE 18492 src\patches\bugs\teleport_squad_to_entity.rs:23 proxy::patches::bugs::teleport_squad_to_entity teleport squad to entity fix initialized
15:40:01.430 TRACE 18492 src\patches\bugs\global_ability_on_entity.rs:18 proxy::patches::bugs::global_ability_on_entity global ability on entity fix initialized
15:40:01.430 TRACE 18492 src\patches\bugs\ability_world_object.rs:14 proxy::patches::bugs::ability_world_object ability world object team fix initialized
15:40:01.432 INFO 18492 src\patches\changes\abilities\spell_blocker.rs:15 proxy::patches::changes::abilities::spell_blocker Ability AbilitySpellBlocker new param hooks initialized
15:40:01.433 INFO 18492 src\patches\changes\mind_control.rs:9 proxy::patches::changes::mind_control Mind control limit bypass initialized
15:40:01.433 INFO 18492 src\patches\changes\elements\card_enum.rs:22 proxy::patches::changes::elements::card_enum Card rendering initialized
15:40:01.434 INFO 18492 src\patches\changes\global_buffs.rs:31 proxy::patches::changes::global_buffs global buffs hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\entity_buffs.rs:14 proxy::patches::changes::entity_buffs entity buffs hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\half_orb.rs:39 proxy::patches::changes::half_orb half orbs hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\map_crc.rs:13 proxy::patches::changes::map_crc Map CRC hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\finished_maps.rs:18 proxy::patches::changes::finished_maps Finished maps hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\new_spells.rs:26 proxy::patches::changes::new_spells New spells hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\new_spell_conditions.rs:39 proxy::patches::changes::new_spell_conditions New spell condition hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\new_spell_rules.rs:19 proxy::patches::changes::new_spell_rules New spell rules hooks initialized
15:40:01.434 INFO 18492 src\patches\changes\context_menu_player.rs:15 proxy::patches::changes::context_menu_player Context menu player extension hooks initialized
15:40:01.435 INFO 18492 src\patches\changes\ability_ability_dependend_ability.rs:53 proxy::patches::changes::ability_ability_dependend_ability Ability AbilityDependendAbility new param hooks initialized
15:40:01.435 INFO 18492 src\patches\changes\ability_immunity.rs:36 proxy::patches::changes::ability_immunity Ability AbilityImmunity new param hooks initialized
15:40:01.435 INFO 18492 src\patches\changes\spell_condition_unit_cap.rs:22 proxy::patches::changes::spell_condition_unit_cap Spell Condition new param hooks initialized
15:40:01.435 INFO 18492 src\patches\changes\ability_dot.rs:17 proxy::patches::changes::ability_dot Ability DOT new param hooks initialized
15:40:01.435 INFO 18492 src\patches\changes\forge_map.rs:22 proxy::patches::changes::forge_map forge map selected
15:40:01.435 INFO 18492 src\patches\changes\hot_keys\replay.rs:23 proxy::patches::changes::hot_keys::replay Replay hooks initialized
15:40:01.436 INFO 18492 src\patches\changes\hot_keys.rs:63 proxy::patches::changes::hot_keys Hot key hooks initialized
15:40:01.436 INFO 18492 src\patches\changes\chat_commands.rs:58 proxy::patches::changes::chat_commands Chat commands hooks initialized
15:40:01.436 INFO 18492 src\patches\changes\void_return.rs:26 proxy::patches::changes::void_return void return hooks initialized
15:40:01.437 INFO 18492 src\patches\changes\building_spawn_animations.rs:41 proxy::patches::changes::building_spawn_animations construction helper hooks initialized
15:40:01.437 INFO 18492 src\patches\changes\max_heal_per_target.rs:18 proxy::patches::changes::max_heal_per_target max heal per target initialized
15:40:01.437 INFO 18492 src\patches\changes\ability_prevent_card_play_enemy_bypass.rs:15 proxy::patches::changes::ability_prevent_card_play_enemy_bypass prevent card play bypass initialized
15:40:01.437 INFO 18492 src\patches\changes\ability_spell_range_modifier_outgoing.rs:17 proxy::patches::changes::ability_spell_range_modifier_outgoing Ability spell range modifier outgoing hooks initialized
15:40:01.437 INFO 18492 src\patches\changes\community_map.rs:57 proxy::patches::changes::community_map community map hooks initialized
15:40:01.438 INFO 18492 src\patches\changes\selection_action_list.rs:27 proxy::patches::changes::selection_action_list Selection action list initialized
15:40:01.438 INFO 18492 src\patches\changes\loca_string_processing.rs:34 proxy::patches::changes::loca_string_processing Loca string processing initialized
15:40:01.438 INFO 18492 src\patches\changes\game_commands.rs:51 proxy::patches::changes::game_commands Game commands hooks initialized
15:40:01.438 INFO 18492 src\patches\changes\ability_set_duration.rs:19 proxy::patches::changes::ability_set_duration Ability effect holder duration hooks initialized
15:40:01.438 INFO 18492 src\patches\changes\spectating\mod.rs:69 proxy::patches::changes::spectating spectating hooks initialized
15:40:01.439 INFO 18492 src\patches\changes\friend_list.rs:9 proxy::patches::changes::friend_list Friend list patches initialized
15:40:01.439 INFO 18492 src\patches\on_tick.rs:28 proxy::patches::on_tick on tick hooks initialized
15:40:01.439 INFO 18492 src\patches\map_presets.rs:43 proxy::patches::map_presets tests hooks initialized
15:40:01.440 INFO 18492 src\patches\files_system.rs:67 proxy::patches::files_system base file system hooks initialized
15:40:01.440 INFO 18492 src\patches\files_system.rs:79 proxy::patches::files_system map file system hooks initialized
15:40:01.440 INFO 18492 src\patches\inventory\mod.rs:38 proxy::patches::inventory inventory hooks initialized
15:40:01.441 INFO 18492 src\networking\connect.rs:203 proxy::networking::connect connect disconnect hooks initialized
15:40:01.441 INFO 18492 src\networking\mod.rs:33 proxy::networking networking hooks initialized
15:40:01.441 WARN 18492 src\lib.rs:181 proxy Initialized successfully
15:40:01.754 WARN 18492 src\game_config\get_set.rs:107 proxy::game_config::get_set application:maxshader '"0"' is not a int
15:40:01.758 INFO 18492 ???:??? Direct3D 9 Start Scan
15:40:01.833 INFO 18492 ???:??? Direct3D 9 Found Adapter: NVIDIA GeForce RTX 4080
15:40:01.833 INFO 18492 ???:??? Direct3D 9 Support Hardware vertex processing
15:40:01.833 INFO 18492 ???:??? Direct3D 9 Support SM 3.0
15:40:01.834 INFO 18492 ???:??? Direct3D 9 Found Adapter: NVIDIA GeForce RTX 4080
15:40:01.834 INFO 18492 ???:??? Direct3D 9 Support Hardware vertex processing
15:40:01.834 INFO 18492 ???:??? Direct3D 9 Support SM 3.0
15:40:01.834 INFO 18492 ???:??? Direct3D 9 End Scan
15:40:01.834 INFO 18492 ???:??? Direct3D 11 Start DXGI scan.
15:40:01.840 INFO 18492 ???:??? Direct3D 11 Found Adapter: NVIDIA GeForce RTX 4080
15:40:01.923 INFO 18492 ???:??? Direct3D 11 Supports Techlevel 11.0
15:40:01.923 INFO 18492 ???:??? Direct3D 11 Found Adapter: NVIDIA GeForce RTX 4080
15:40:01.959 INFO 18492 ???:??? Direct3D 11 Supports Techlevel 11.0
15:40:01.960 INFO 18492 ???:??? Direct3D 11 End DXGI scan.
15:40:01.960 INFO 18492 ???:??? app BattleForge Final Build: 400055 (Retail branch)
15:40:01.960 INFO 18492 ???:??? app CPU GenuineIntel family 6 (P2, P3, Pentium-M, Athlon) detected.
15:40:01.960 WARN 18492 D:\Skylords\build\test\proxy\bf_utils\src\fs\pak_files.rs:121 bf_utils::fs::pak_files Loading paks from: '"base\\pak"' (base/pak/)
15:40:01.960 INFO 18492 bf_utils\src\fs\pak_files.rs:152 bf_utils::fs::pak_files loading files from: base\pak
15:40:02.024 WARN 18492 D:\Skylords\build\test\proxy\bf_utils\src\fs\pak_files.rs:129 bf_utils::fs::pak_files Loading paks from: '"base/pak-test/"' (base/pak-test/)
15:40:02.024 INFO 18492 bf_utils\src\fs\pak_files.rs:152 bf_utils::fs::pak_files loading files from: base/pak-test/
15:40:02.277 INFO 18492 ???:??? app CPU clock speed is ~3417 MHz.
15:40:02.277 INFO 18492 ???:??? app CPU has 24 cores.
15:40:02.277 INFO 18492 ???:??? app CPU rating is 0 points.
15:40:02.277 INFO 18492 ???:??? app GFX rating is 0 points.
15:40:02.277 INFO 18492 ???:??? CreateDisplay Creating Display DirectX 11
15:40:02.301 INFO 18492 ???:??? Create Device Use Direct3D 11
15:40:02.305 INFO 18492 ???:??? GPU Vendor ID = 0x10de
15:40:02.305 INFO 18492 ???:??? GPU Device ID = 0x2704
15:40:02.305 INFO 18492 ???:??? GPU Subsystem ID = 0x88ca1043
15:40:02.305 INFO 18492 ???:??? GPU Revision = 0xa1
15:40:02.305 INFO 18492 ???:??? GPU Device description = NVIDIA GeForce RTX 4080
15:40:02.305 INFO 18492 ???:??? GPU Video Memory = -1073741824
15:40:02.305 INFO 18492 ???:??? GPU System Memory = 0
15:40:02.305 INFO 18492 ???:??? GPU Shared Memory = 1073676288
15:40:02.314 WARN 18492 ???:??? Direct3D 11 Create shader resource view : S_OK
15:40:02.695 TRACE 23604 src\patches\inventory\mod.rs:80 proxy::patches::inventory loading took: 424.0157ms
15:40:03.207 INFO 12756 src\patches\changes\affinities_highlighting.rs:52 proxy::patches::changes::affinities_highlighting highlight all cards differences took: 339.4901ms
15:40:03.224 INFO 12756 ???:??? PssSoundPipeline Loading soundpipeline:

 Version: 1
Number of Files:10227
15:40:03.329 INFO 12756 ???:??? PssSoundPipeline Loading soundpipeline bf1/sound/SoundPipeline_en.bin finished, 40715.29 seconds of music material in pipe.
15:40:03.349 INFO 12756 ???:??? CPssPhysicalRenderer Got Stereo speakers!
15:40:03.423 INFO 12756 ???:??? VisManager Loaded 4 different playercolors.
15:40:03.423 INFO 12756 ???:??? VisManager Loaded 5 Flame Locator mappings
15:40:03.423 INFO 12756 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
15:40:03.901 INFO 12756 ???:??? UI (Lua) [=?]: [datetime_utils.lub:11] Time locale set to: English_United States.1252
15:40:03.906 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:18] Created new anonymous task
15:40:03.906 INFO 12756 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:16] Restoring inventory card click handler to default
15:40:03.906 INFO 12756 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:27] Setting inventory card click handler to 'add_to_deck'
15:40:03.908 INFO 12756 ???:??? UI (Lua) [=?]: [event_listener.lub:20] Register EventListener with id 55
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to Login
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'Login'
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/login/pg_login_view.xml)
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/login/pg_login_view.xml
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #0
15:40:04.003 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/login/pg_login_view.xml
15:40:04.015 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:13] Created new task ReEnableLogin
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:04.017 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:04.018 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:06.462 INFO 12756 src\networking\connect.rs:257 proxy::networking::connect connecting
15:40:06.462 INFO 12756 src\networking\connect.rs:320 proxy::networking::connect forge_connect
15:40:06.720 INFO 12756 src\networking\connect.rs:359 proxy::networking::connect connected TlsStream { io: PollEvented { io: Some(TcpStream { addr: 192.168.50.213:51097, peer: 162.55.91.56:7501, socket: 19028 }) }, session: ClientConnection, state: Stream }
15:40:06.720 INFO 12756 src\networking\connect.rs:262 proxy::networking::connect fully connected
15:40:06.729 WARN 12756 src\patches\changes\chat_commands.rs:154 proxy::patches::changes::chat_commands Adding mass import export commands
15:40:06.729 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:215] Adding notification listener...
15:40:06.729 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:217] Notification listener added!
15:40:06.729 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:116] Adding packet listener...
15:40:06.729 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:118] Packet listener added!
15:40:06.735 DEBUG 16112 sr_packets\src\from_game\borderline.rs:8 sr_packet::from_game::borderline::BorderlineMessage_LoginAccountRMC sending msg: 0xa:14 LoginAccountRMC { data: LoginAccountData { email: "****", password: "***" } }
15:40:07.014 DEBUG 16112 from_server\src\borderline.rs:70 from_server::borderline::BorderlineMessage_LoginAccountRMR received msg: 0xa:1e LoginAccountRMR { data: LoginAccountRMR { result: Success, warning_type: WarnNone, account: Some(LoginAccountResult { session: 96, token: 11517649050824896728, character: Some(CNetWorldPlayerCompressedVO { guid: 849, gold: 4888, name: "Weak1ings", portrait: 5003, border: 0, texture: 0, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 3088, elo: 0, highest_elo: 0 }), bfp: 53, relics: 10000000 }) } }
15:40:07.014 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetPlayerServerSettings: []
15:40:07.014 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetPlayerServerSettingsRMC sending msg: 0x1:2e GetPlayerServerSettingsRMC { request: 8 }
15:40:07.141 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetPlayerServerSettings: {"Debug":{"ForcedMapSeed":0,"ShowScriptGoals":false},"Gm":{"AlwaysWin":false,"DoNotShowInRegion":false},"NotifyPromoDrop":1,"PreferredServer":"","PrivateLobbies":0,"ProfileVisibility":2,"ReceivePromoDropNotifications":2}
15:40:07.141 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetServerTunables: []
15:40:07.141 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetServerTunablesRMC sending msg: 0x1:45 GetServerTunablesRMC { request: 9 }
15:40:07.404 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetServerTunables
15:40:07.474 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetForgeThemes: []
15:40:07.474 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetForgeThemesRMC sending msg: 0x1:51 GetForgeThemesRMC { request: 10 }
15:40:07.602 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetForgeThemes: {"ErrorCode":0,"ForgeThemes":[{"EndDay":0,"EndMonth":0,"Id":1,"IsFeatured":false,"IsOwned":true,"StartDay":0,"StartMonth":0},{"EndDay":1,"EndMonth":3,"Id":2,"IsFeatured":true,"IsOwned":true,"StartDay":1,"StartMonth":12},{"EndDay":1,"EndMonth":6,"Id":3,"IsFeatured":true,"IsOwned":false,"StartDay":1,"StartMonth":3},{"EndDay":1,"EndMonth":9,"Id":4,"IsFeatured":true,"IsOwned":false,"StartDay":1,"StartMonth":6},{"EndDay":1,"EndMonth":12,"Id":5,"IsFeatured":true,"IsOwned":false,"StartDay":31,"StartMonth":10},{"EndDay":0,"EndMonth":0,"Id":6,"IsFeatured":false,"IsOwned":false,"StartDay":0,"StartMonth":0},{"EndDay":0,"EndMonth":0,"Id":7,"IsFeatured":false,"IsOwned":false,"StartDay":0,"StartMonth":0},{"EndDay":0,"EndMonth":0,"Id":8,"IsFeatured":false,"IsOwned":false,"StartDay":0,"StartMonth":0},{"EndDay":0,"EndMonth":0,"Id":9,"IsFeatured":false,"IsOwned":false,"StartDay":0,"StartMonth":0}]}
15:40:07.602 INFO 12756 src\patches\changes\forge_map.rs:22 proxy::patches::changes::forge_map forge map selected
15:40:07.609 DEBUG 16112 from_server\src\borderline.rs:70 from_server::borderline::BorderlineMessage_LoginCharacterRMR received msg: 0xa:19 LoginCharacterRMR
15:40:07.621 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllLimitedPoolsForCharacterRMC sending msg: 0x7:f GetAllLimitedPoolsForCharacterRMC
15:40:07.748 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetAllLimitedPoolsForCharacterRMR received msg: 0x7:37 GetAllLimitedPoolsForCharacterRMR { error: 0, limited_pools: [LimitedPool { pool_id: 1, create_date: BFDateTime { index: 0, year: 2013, month: 10, day: 10, hour: 10, minute: 10, second: 10 }, close_date: BFDateTime { index: 0, year: 2043, month: 10, day: 10, hour: 10, minute: 10, second: 10 }, status: 0 }] }
15:40:07.901 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllCardsForCharacterCompressedRMC sending msg: 0x7:26 GetAllCardsForCharacterCompressedRMC
15:40:08.064 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetAllCardsForCharacterCompressedRMR received msg: 0x7:4e GetAllCardsForCharacterCompressedRMR { cardpools: [CardPool { cards_size: 604, pool: 1, limited_pool_id: 0 }, CardPool { cards_size: 0, pool: 3, limited_pool_id: 1 }] }
15:40:08.064 INFO 16112 from_server\src\pregame.rs:790 from_server::pregame::internal Pool: 1, Limited pool id: 0
15:40:08.064 INFO 16112 from_server\src\pregame.rs:790 from_server::pregame::internal Pool: 2, Limited pool id: 0
15:40:08.064 INFO 16112 from_server\src\pregame.rs:790 from_server::pregame::internal Pool: 3, Limited pool id: 1
15:40:08.073 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllCardUpgradesForCharacterCompressedRMC sending msg: 0x7:24 GetAllCardUpgradesForCharacterCompressedRMC
15:40:08.200 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetAllCardUpgradesForCharacterCompressedRMR received msg: 0x7:4c GetAllCardUpgradesForCharacterCompressedRMR { upgrades: Upgrades { upgrades_size: 0 } }
15:40:08.209 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllBoostersForCharacterRMC sending msg: 0x7:21 GetAllBoostersForCharacterRMC
15:40:08.336 INFO 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetAllBoostersForCharacterRMR received msg: 0x7:49 GetAllBoostersForCharacterRMR
15:40:08.345 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllDecksForCharacterRMC sending msg: 0x7:1c GetAllDecksForCharacterRMC
15:40:08.481 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetAllDecksForCharacterRMR received msg: 0x7:43 GetAllDecksForCharacterRMR { tags: {593: DeckTag { guid: 593, name: "EncountersWithTwilight", icon: 0, color: 22, is_renameable: false, is_removable: true }, 586: DeckTag { guid: 586, name: "TheGunsOfLyr", icon: 0, color: 22, is_renameable: false, is_removable: true }, 590: DeckTag { guid: 590, name: "SlaveMaster", icon: 0, color: 22, is_renameable: false, is_removable: true }, 595: DeckTag { guid: 595, name: "DefendingHope", icon: 0, color: 22, is_renameable: false, is_removable: true }, 615: DeckTag { guid: 615, name: "Speedrun", icon: 0, color: 9, is_renameable: false, is_removable: true }, 582: DeckTag { guid: 582, name: "TheTreasureFleet", icon: 0, color: 22, is_renameable: false, is_removable: true }, 591: DeckTag { guid: 591, name: "Ascension", icon: 0, color: 22, is_renameable: false, is_removable: true }, 599: DeckTag { guid: 599, name: "Mo", icon: 0, color: 22, is_renameable: false, is_removable: true }, 587: DeckTag { guid: 587, name: "KingOfTheGiants", icon: 0, color: 22, is_renameable: false, is_removable: true }, 611: DeckTag { guid: 611, name: "P3", icon: 0, color: 7, is_renameable: false, is_removable: true }, 596: DeckTag { guid: 596, name: "PassageToDarkness", icon: 0, color: 22, is_renameable: false, is_removable: true }, 594: DeckTag { guid: 594, name: "Convoy", icon: 0, color: 22, is_renameable: false, is_removable: true }, 580: DeckTag { guid: 580, name: "Sunbridge", icon: 0, color: 22, is_renameable: false, is_removable: true }, 597: DeckTag { guid: 597, name: "Blight", icon: 0, color: 22, is_renameable: false, is_removable: true }, 589: DeckTag { guid: 589, name: "BehindEnemyLines", icon: 0, color: 22, is_renameable: false, is_removable: true }, 585: DeckTag { guid: 585, name: "NightmareShard", icon: 0, color: 22, is_renameable: false, is_removable: true }, 588: DeckTag { guid: 588, name: "Titans", icon: 0, color: 22, is_renameable: false, is_removable: true }, 602: DeckTag { guid: 602, name: "Empire", icon: 0, color: 22, is_renameable: false, is_removable: true }, 601: DeckTag { guid: 601, name: "Oracle", icon: 0, color: 22, is_renameable: false, is_removable: true }, 605: DeckTag { guid: 605, name: "HeartOfTrouble", icon: 0, color: 22, is_renameable: false, is_removable: true }, 604: DeckTag { guid: 604, name: "IntoTheJungle", icon: 0, color: 22, is_renameable: false, is_removable: true }, 598: DeckTag { guid: 598, name: "Ocean", icon: 0, color: 22, is_renameable: false, is_removable: true }, 603: DeckTag { guid: 603, name: "UnexpectedVisitors", icon: 0, color: 22, is_renameable: false, is_removable: true }, 612: DeckTag { guid: 612, name: "P4", icon: 0, color: 7, is_renameable: false, is_removable: true }, 614: DeckTag { guid: 614, name: "Event", icon: 0, color: 9, is_renameable: false, is_removable: true }, 578: DeckTag { guid: 578, name: "Crusade", icon: 0, color: 22, is_renameable: false, is_removable: true }, 606: DeckTag { guid: 606, name: "Campaign PvE", icon: 0, color: 25, is_renameable: false, is_removable: true }, 577: DeckTag { guid: 577, name: "SiegeOfHope", icon: 0, color: 22, is_renameable: false, is_removable: true }, 581: DeckTag { guid: 581, name: "TheDwarvenRiddle", icon: 0, color: 22, is_renameable: false, is_removable: true }, 607: DeckTag { guid: 607, name: "Random PvE", icon: 0, color: 25, is_renameable: false, is_removable: true }, 608: DeckTag { guid: 608, name: "Community PvE", icon: 0, color: 25, is_renameable: false, is_removable: true }, 613: DeckTag { guid: 613, name: "Season", icon: 0, color: 9, is_renameable: false, is_removable: true }, 592: DeckTag { guid: 592, name: "NightmaresEnd", icon: 0, color: 22, is_renameable: false, is_removable: true }, 609: DeckTag { guid: 609, name: "P1", icon: 0, color: 7, is_renameable: false, is_removable: true }, 610: DeckTag { guid: 610, name: "P2", icon: 0, color: 7, is_renameable: false, is_removable: true }, 579: DeckTag { guid: 579, name: "BadHarvest", icon: 0, color: 22, is_renameable: false, is_removable: true }, 583: DeckTag { guid: 583, name: "TheInsaneGod", icon: 0, color: 22, is_renameable: false, is_removable: true }, 584: DeckTag { guid: 584, name: "TheSoultree", icon: 0, color: 22, is_renameable: false, is_removable: true }, 600: DeckTag { guid: 600, name: "RavensEnd", icon: 0, color: 22, is_renameable: false, is_removable: true }, 576: DeckTag { guid: 576, name: "Tutorial", icon: 0, color: 13, is_renameable: false, is_removable: false }, 575: DeckTag { guid: 575, name: "Free PvP", icon: 0, color: 6, is_renameable: false, is_removable: false }}, decks: Decks { decks_size: 2 } }
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile .\bf1\map\PVP\12601_PvP_02p_Skjal2.map doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile .\bf1\map\PVP\12601_PvP_02p_Skjal2.map doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_1vs1 doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_1vs1 doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_2vs2 doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_2vs2 doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_1P doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_1P doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_2P doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_2P doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_4P doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_4P doesn't exist!
15:40:08.497 INFO 12756 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_3vs3 doesn't exist!
15:40:08.497 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_3vs3 doesn't exist!
15:40:10.247 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetFinishedMapsForCharacterRMC sending msg: 0x8:42 GetFinishedMapsForCharacterRMC
15:40:10.374 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetFinishedMapsForCharacterRMR received msg: 0x8:58 GetFinishedMapsForCharacterRMR { maps: [FinishedMap { id: 8, difficulty: 3 }, FinishedMap { id: 9, difficulty: 3 }, FinishedMap { id: 10, difficulty: 3 }, FinishedMap { id: 18, difficulty: 3 }, FinishedMap { id: 19, difficulty: 3 }, FinishedMap { id: 20, difficulty: 3 }, FinishedMap { id: 21, difficulty: 3 }, FinishedMap { id: 22, difficulty: 3 }, FinishedMap { id: 24, difficulty: 3 }, FinishedMap { id: 25, difficulty: 3 }, FinishedMap { id: 26, difficulty: 3 }, FinishedMap { id: 29, difficulty: 3 }, FinishedMap { id: 32, difficulty: 3 }, FinishedMap { id: 35, difficulty: 3 }, FinishedMap { id: 37, difficulty: 3 }, FinishedMap { id: 44, difficulty: 3 }, FinishedMap { id: 45, difficulty: 3 }, FinishedMap { id: 56, difficulty: 3 }, FinishedMap { id: 57, difficulty: 3 }, FinishedMap { id: 60, difficulty: 3 }, FinishedMap { id: 74, difficulty: 3 }, FinishedMap { id: 84, difficulty: 3 }, FinishedMap { id: 88, difficulty: 3 }, FinishedMap { id: 98, difficulty: 3 }, FinishedMap { id: 99, difficulty: 3 }, FinishedMap { id: 100, difficulty: 3 }, FinishedMap { id: 128, difficulty: 3 }, FinishedMap { id: 129, difficulty: 3 }, FinishedMap { id: 131, difficulty: 3 }, FinishedMap { id: 67, difficulty: 1 }] }
15:40:10.383 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_GetFriendListRMC sending msg: 0x4:7b GetFriendListRMC
15:40:10.511 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_GetFriendListRMR received msg: 0x4:4e GetFriendListRMR { friends: WorldPlayerCompressedListVO { player_count: 0 } }
15:40:10.519 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_GetIgnoreListRMC sending msg: 0x4:78 GetIgnoreListRMC
15:40:10.648 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_GetIgnoreListRMR received msg: 0x4:4b GetIgnoreListRMR { ignores: WorldPlayerCompressedListVO { player_count: 0 } }
15:40:10.653 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 3, map: NotAMap }
15:40:10.779 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 3, players: [] }
15:40:10.790 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 4, map: NotAMap }
15:40:10.917 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 4, players: [] }
15:40:10.926 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 5, map: NotAMap }
15:40:11.052 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 5, players: [] }
15:40:11.061 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 6, map: NotAMap }
15:40:11.188 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 6, players: [] }
15:40:11.195 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 1, map: Introduction }
15:40:11.322 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 1073, players: [CNetWorldPlayerCompressedVO { guid: 849, gold: 0, name: "Weak1ings", portrait: 5003, border: 0, texture: 0, title: 0, is_gm: false, state: 0, location: 0, map: Introduction, experience: 3088, elo: 0, highest_elo: 0 }] }
15:40:11.330 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:11.331 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 1, state: 1, map: NotAMap }
15:40:11.352 TRACE 12756 src\patches\pvp_match.rs:212 proxy::patches::pvp_match './bf1/map/sandbox\19000_Sandbox01\config\map_settings.json': Not found
15:40:11.352 TRACE 12756 src\patches\pvp_match.rs:287 proxy::patches::pvp_match V1(JsonMapConfigV1 { map_end_tick: None, hide_pvp_map_end_timer: None, hide_pvp_map_score: None, on_draw_add_looping_extension_ticks: None, perform_draw_check_at_remaining_ticks_before_end: None, extension_ticks: None })
15:40:11.352 WARN 12756 ???:??? gd log "[%i] loading map: %s"
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionLog
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorGeneratorTypeChange
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
15:40:11.354 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
15:40:11.355 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
15:40:11.355 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
15:40:11.355 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
15:40:11.355 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
15:40:11.355 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorPlayerLeft
15:40:11.371 INFO 12756 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
15:40:11.373 INFO 12756 ???:??? Pathing.Manager CPathManagerConstructed
15:40:11.476 DEBUG 12756 src\patches\pvp_match.rs:301 proxy::patches::pvp_match default? true
15:40:11.476 DEBUG 12756 src\patches\pvp_match.rs:309 proxy::patches::pvp_match timer shown? true
15:40:11.476 DEBUG 12756 src\patches\pvp_match.rs:317 proxy::patches::pvp_match score shown? true
15:40:11.476 DEBUG 12756 src\patches\pvp_match.rs:342 proxy::patches::pvp_match match extending disable true
15:40:11.491 DEBUG 12756 src\game_config\get_set.rs:24 proxy::game_config::get_set debugmainmenu:stickyinfo = 
15:40:11.492 INFO 12756 ???:??? VisManager Loaded 4 different playercolors.
15:40:11.493 INFO 12756 ???:??? VisManager Loaded 5 Flame Locator mappings
15:40:11.493 INFO 12756 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
15:40:11.497 INFO 12756 ???:??? CVisSoundWorldPlugin Loaded 3 Reverb settings, defaults to bf1/sound/ir_presets/default.ifr with dry level 0.030000
15:40:11.509 INFO 12756 ???:??? VisMain Starting Build Scene...
15:40:12.585 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 1
15:40:12.585 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:12.732 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:383] Online Mode initialize!
15:40:12.732 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:158] Initialize Persistent Views
15:40:12.733 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:12.733 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:12.733 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:74] show playerlist
15:40:12.733 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:12.735 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:12] AddDelegates...PlayerListController...
15:40:12.760 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:22] Initializing inventory controller.
15:40:12.760 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:48] Adding inventory controller delegates.
15:40:13.442 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:388] Toggling show inventory: 0
15:40:13.442 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:396] Switching inventory tabs.
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:164] Updating mode selection.
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:258] Attempting to select cardpool by dropdown item index: -1
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:187] Cardpool found with id:1
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:187] Cardpool found with id:2
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:187] Cardpool found with id:3
15:40:13.443 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:258] Attempting to select cardpool by dropdown item index: 0
15:40:13.444 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:241] Updating inventory filter categories.
15:40:13.444 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:291] Resetting inventory filters.
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 0
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 7
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 6
15:40:13.448 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:13.451 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:13] Initializing deck bar controller.
15:40:13.451 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:22] Initializing deck bar delegates.
15:40:13.454 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:17] EventWindowController...Initialize
15:40:13.454 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:19] ...done
15:40:13.454 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:30] EventWindowController:AddDelegates()
15:40:13.457 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:13] Created new task RefreshPlayerRegionList
15:40:13.457 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:46] Initialize...PlayerLocationSearchController...
15:40:13.457 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:126] InitializeScreen...PlayerLocationSearchController...
15:40:13.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:62] PlayerLocationSearchController...AddDelegates...
15:40:13.507 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:222] Checking for new mail ... checkServer=1
15:40:13.518 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetInGameMailRMC sending msg: 0x7:14 GetInGameMailRMC
15:40:13.651 INFO 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetInGameMailRMR received msg: 0x7:3c GetInGameMailRMR
15:40:13.667 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:202] NavigationController.ActivateNewMailFX
15:40:13.668 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
15:40:13.668 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:18] AddDelegates...NavigationController
15:40:13.671 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:386] After Persistent Views initializing!
15:40:13.671 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to News
15:40:13.671 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'Login'
15:40:13.671 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'News'
15:40:13.671 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/news/pg_news_view.xml
15:40:13.715 INFO 12756 ???:??? UI (Lua) [=?]: [pg_leaderboard_controller.lub:23] LeaderboardController:__init()
15:40:13.716 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:13.716 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:13.716 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:13.720 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetCharacterStatisticRMC sending msg: 0x7:1e GetCharacterStatisticRMC
15:40:13.850 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetCharacterStatisticRMR received msg: 0x7:46 GetCharacterStatisticRMR { wins: [0, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 0, 0, 0, 0], stats: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0], cards: 606, elo: 0, time_in_pve_matches: 572, time_in_pvp_matches: 0 }
15:40:13.853 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetQuestsAndAchievements: []
15:40:13.853 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetQuestsAndAchievementsRMC sending msg: 0x1:f GetQuestsAndAchievementsRMC { request: 11 }
15:40:13.853 INFO 12756 ???:??? UI (Lua) [=?]: [pg_news_controller.lub:283] NewsController:SwitchTab(1)
15:40:13.853 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetIngameEventNews: {"Language":"en"}
15:40:13.853 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetIngameEventNewsRMC sending msg: 0x1:33 GetIngameEventNewsRMC { request: 12, language: "en" }
15:40:13.853 INFO 12756 ???:??? UI (Lua) [=?]: [pg_news_controller.lub:170] NewsController:Initialize() 
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to News
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_inventory_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_playerlist_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_navigation_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_deck_bar_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_request_list_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_public_chatwindow_wnd State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_ls_playerlist_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_community_goals_view State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_private_chatwindow_wnd State: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 1
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:124] Deckbuilder is ParentView
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:132] ShowPregameSections
15:40:13.854 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
15:40:13.857 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:13.857 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 2, state: 1, map: NotAMap }
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:321] Deckbuilder Loaded!
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:654] DeckBarController:OnPregame
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:256] Attempt at toggling deckbar status. New status 1
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:272] Did not toggle deckbar active status
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:664] Inializing deck slots.
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:208] Current deck changed.
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:278] Deck changed to test 2
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:602] Toggled deck edit mode. New status 0
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:287] Updating deck bar with deck test 2
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:169] Updating lock deck button
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:215] Updating deck name
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:297] Resetting all card slots
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:223] Updating card slots
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:202] Updating cover card
15:40:13.860 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:183] Updating new deck button
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:128] Set ExitBtnState 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.861 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:160] Change contacts button icon
15:40:13.862 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.862 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.862 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
15:40:13.862 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
15:40:13.981 WARN 16112 src\networking\connect.rs:850 proxy::networking::connect LOGIN: 7.2607377s
15:40:13.986 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetQuestsAndAchievements
15:40:14.009 INFO 12756 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVtoo_many_args@io@boost@@: boost::too_many_args: format-string refered to less arguments than were passed
15:40:14.572 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetIngameEventNews
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_MapOfTheDayNotification: {"DateTimestamp":1773396000,"DoneToday":false,"Map":60}
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_MapInfectionNotification: {"DoneThisWeek":false,"Infections":[{"DateTimestamp":1773050400,"Element":2,"Map":9,"ModifiersPerDifficulty":{"1":[2031000,2031047],"2":[2031000,2031047],"3":[2031000,2031047,2031040]}},{"DateTimestamp":1773050400,"Element":3,"Map":101,"ModifiersPerDifficulty":{"1":[2031011,2031048],"2":[2031011,2031048],"3":[2031011,2031048,2031041]}},{"DateTimestamp":1773050400,"Element":3,"Map":102,"ModifiersPerDifficulty":{"1":[2031011,2031048],"2":[2031011,2031048],"3":[2031011,2031048,2031041]}},{"DateTimestamp":1773050400,"Element":3,"Map":103,"ModifiersPerDifficulty":{"1":[2031011,2031048],"2":[2031011,2031048],"3":[2031011,2031048,2031041]}}]}
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonNotification: {"Season":{"EndTimestamp":1782864000,"Guid":3,"StartTimestamp":1772359200}}
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetServerTunables: []
15:40:14.574 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetServerTunablesRMC sending msg: 0x1:45 GetServerTunablesRMC { request: 13 }
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonChallengeNotification: {"Challenges":[],"HasCollectables":false,"Season":3}
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonProgressNotification: {"HasCollectables":false,"Milestones":[],"Points":0.0,"Season":3}
15:40:14.574 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
15:40:14.836 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetServerTunables
15:40:19.726 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to StoryBook
15:40:19.726 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'News'
15:40:19.726 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'StoryBook'
15:40:19.726 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/storybook/pg_storybook_view.xml
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_storybook_controller.lub:18] StoryBookController:__init()
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 57
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:19.742 INFO 12756 ???:??? UI (Lua) [=?]: [pg_storybook_controller.lub:93] StoryBookController:Initialize()
15:40:19.745 INFO 12756 ???:??? UI (Lua) [=?]: [pg_storybook_controller.lub:207] SwitchMainTab...StoryBookController...Profile
15:40:19.746 ERROR 12756 src\networking\connect.rs:212 proxy::networking::connect BUG, this should never happen, packet is already expected
15:40:19.757 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetCharacterStatisticRMC sending msg: 0x7:1e GetCharacterStatisticRMC
15:40:19.887 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_GetCharacterStatisticRMR received msg: 0x7:46 GetCharacterStatisticRMR { wins: [0, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 0, 0, 0, 0], stats: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0], cards: 606, elo: 0, time_in_pve_matches: 572, time_in_pvp_matches: 0 }
15:40:19.891 INFO 12756 ???:??? UI (Lua) [=?]: [pg_storybook_controller.lub:582] SwitchProfileTab...StoryBookController: Main
15:40:19.901 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'StoryBook'
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'GameWizard'
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:48] Initialize GameSection!
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml)
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #0
15:40:23.588 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 16
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:14] WorldmapController...Initialize
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:16] ...done
15:40:23.647 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:120] WorldmapController:AddDelegates()
15:40:23.648 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:230] WorldmapController:InitializeHappyHourIndicator()
15:40:23.648 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:239] WorldmapController:UpdateHappyHourIndicator(true)
15:40:23.648 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:258] Hide worldmap happy hour indicator.
15:40:23.652 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:268] WorldmapController...Update...
15:40:23.656 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
15:40:23.782 INFO 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
15:40:23.791 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
15:40:23.917 INFO 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
15:40:23.943 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:23.943 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:23.943 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:23.943 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:23.943 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:14] WorldmapController...Initialize
15:40:23.947 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:268] WorldmapController...Update...
15:40:23.948 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
15:40:24.074 INFO 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
15:40:24.083 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
15:40:24.210 INFO 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
15:40:24.241 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:24.241 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetCommunityGoals: []
15:40:24.241 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_GetCommunityGoalsRMC sending msg: 0x1:75 GetCommunityGoalsRMC { request: 14 }
15:40:24.244 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:24.244 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:24.244 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:24.244 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:24.378 INFO 12756 ???:??? UI (Lua) [=?] Received response Network_GetCommunityGoals: {"CommunityGoals":[{"CurContribution":0,"CurProgress":72210,"EndTimestamp":1782864000,"Guid":2,"Maps":[],"MaxProgress":1000000000,"MinContribution":50000,"Rewards":[{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":500000000,"Relics":0,"SeasonPoints":10.0},{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":1000000000,"Relics":0,"SeasonPoints":10.0}],"StartTimestamp":1772359200},{"CurContribution":0,"CurProgress":2,"EndTimestamp":1782864000,"Guid":3,"Maps":[],"MaxProgress":60000,"MinContribution":10,"Rewards":[{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":30000,"Relics":0,"SeasonPoints":15.0},{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":60000,"Relics":0,"SeasonPoints":15.0}],"StartTimestamp":1772359200},{"CurContribution":0,"CurProgress":36,"EndTimestamp":1782864000,"Guid":4,"Maps":[],"MaxProgress":1000000,"MinContribution":100,"Rewards":[{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":500000,"Relics":0,"SeasonPoints":10.0},{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[],"ForgeThemes":[],"Gold":0,"Milestone":1000000,"Relics":0,"SeasonPoints":10.0}],"StartTimestamp":1772359200},{"CurContribution":0,"CurProgress":0,"EndTimestamp":1782864000,"Guid":5,"Maps":[],"MaxProgress":3,"MinContribution":3,"Rewards":[{"Bfp":0.0,"Boosters":[],"Cards":[],"Cosmetics":[{"Id":56,"Type":2}],"ForgeThemes":[],"Gold":0,"Milestone":3,"Relics":0,"SeasonPoints":0.0}],"StartTimestamp":1772359200}]}
15:40:25.758 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:545] WorldmapController:OnSelectMap(101)
15:40:25.765 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_LeaveChatChannelRMC sending msg: 0x6:14 LeaveChatChannelRMC { channel: 1073 }
15:40:25.891 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_LeaveChatChannelRMR received msg: 0x6:1a LeaveChatChannelRMR
15:40:25.899 DEBUG 16112 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 1, map: RPvEOnePlayer }
15:40:26.026 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 1617, players: [CNetWorldPlayerCompressedVO { guid: 849, gold: 0, name: "Weak1ings", portrait: 5003, border: 0, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: RPvEOnePlayer, experience: 3088, elo: 0, highest_elo: 0 }] }
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:150] OnChatRegionChanged...PlayerLocationSearchController...
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:55] ScreenFlow:Next()
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:72] current screen:bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_ranked_games_view.xml
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_user_generated_view.xml
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pvp_view.xml
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:78] condition hit!
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml)
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:527] WorldmapController...Close...
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:529] ...RemoveAllDelegates
15:40:26.034 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:181] WorldmapController:RemoveAllDelegates()
15:40:26.035 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
15:40:26.035 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #1
15:40:26.035 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
15:40:26.060 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:113] PvEGameSelectionController...AddDelegates...
15:40:26.060 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:44] GameSelectionControllerBase:AddDelegates()
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 44
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:19] PvEGameSelectionController...Initialize
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:22] ...done.
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:25] GameSelectionControllerBase...Initialize...
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:363] GameSelectionControllerBase...CheckNextBtnState...
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:365] ...leader, hiding next button.
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:229] PvEGameSelectionController...UpdateDifficultyButtons...
15:40:26.063 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:26.081 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: RPvEOnePlayer }
15:40:26.208 INFO 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:438] GameSelectionControllerBase... CheckForCorrectJoinBtnState...
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:283] UpdateDeckMode...GameSelectionControllerBase.. - early out, no sufficient widgets!
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:363] GameSelectionControllerBase...CheckNextBtnState...
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:365] ...leader, hiding next button.
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:398] GameSelectionControllerBase...CheckCreateBtnState...
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:415] ...no match maker concrete, enable.
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:438] GameSelectionControllerBase... CheckForCorrectJoinBtnState...
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:363] GameSelectionControllerBase...CheckNextBtnState...
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:365] ...leader, hiding next button.
15:40:26.211 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:26.215 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.215 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.215 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.215 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.218 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.218 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.218 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.218 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.219 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.219 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.220 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.220 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.220 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:26.220 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:26.220 INFO 12756 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:181] WorldmapController:RemoveAllDelegates()
15:40:28.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.494 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.509 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.540 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.590 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.604 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:31.620 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:42] PvEGameSelectionController:OnDifficultySelected
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:338] PvEGameSelectionController...OnBtnCreate...
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:468] GameSelectionControllerBase...OnBtnCreate...
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [match_utils.lub:60] remove automatch...
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [match_utils.lub:69] ...no match maker
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:312] GameSelectionControllerBase...Close...
15:40:35.322 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:319] GameSelectionControllerBase:RemoveAllDelegates()
15:40:35.324 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_CreateCustomGameRMC sending msg: 0x8:43 CreateCustomGameRMC { deck: 117, map: RPvEOnePlayer, difficulty: 5, reward_mode: 3, pvp: false, unlimited_deck_mode: false, map_file_checksum: 0, combined_checksum: 0, map_names: [], map_of_the_month: true, is_infected: false, map_of_the_season_preset: 0, lobby_description: "", modifiers: [] }
15:40:35.471 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CreateCustomGameRMR received msg: 0x8:59 CreateCustomGameRMR { error: 0, player: 849, match_id: 62, pvp: false }
15:40:35.471 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameCreatedNotification received msg: 0x8:2a CustomGameCreatedNotification { player: 849, map: RPvEOnePlayer, difficulty: 5, match_id: 62, pvp: false, is_open: true, unlimited_deck_mode: false, map_file_cc: 0, combined_crc: 0, is_motd: false, is_infected: false, map_of_the_season_preset: 0, lobby_description: "", modifiers: [] }
15:40:35.471 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 62, map: RPvEOnePlayer, pvp: false, team1: [Some(MatchPlayer { id: 849, name: "Weak1ings", deck_name: "test 2", cover_card: 4051, ready: false, pve_level: 3, elo: 0, deck_level: 54 })], team2: [] }
15:40:35.474 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:35.474 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:35.474 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
15:40:35.475 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:120] OnMatchCreated...EventWindowController
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:55] ScreenFlow:Next()
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:72] current screen:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:78] condition hit!
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml)
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:312] GameSelectionControllerBase...Close...
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:319] GameSelectionControllerBase:RemoveAllDelegates()
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #2
15:40:35.478 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 45
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 47
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:35.496 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:35.497 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:35.497 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:26] MatchmakingController...Initialize
15:40:35.506 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:35.506 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 4, state: 1, map: RPvEOnePlayer }
15:40:35.507 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:29] ...done
15:40:35.507 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
15:40:35.508 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:596] MatchmakingController...CreateTeamSlotList...
15:40:35.508 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:604] TeamFound: 4
15:40:35.509 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:608] SlotCount: 1
15:40:35.509 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:611] MatchmakingController...CreateTeamSlotList...CreatePlayerBanner...
15:40:35.518 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:616] Adding teamslot to team1: 1
15:40:35.518 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:604] TeamFound: 7
15:40:35.519 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:1138]  infos = {"MapOfTheSeasonPreset":0,"InfectionElement":0,"Modifiers":[],"LobbyDescription":"","IsInfected":false}
15:40:35.519 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:185] MatchmakingController:AddDelegates()1
15:40:35.520 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
15:40:35.521 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...1
15:40:35.529 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_SetSpeedRunRMR received msg: 0x8:4c SetSpeedRunRMR
15:40:35.531 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:35.533 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:35.533 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:35.533 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
15:40:35.534 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:1138]  infos = {"MapOfTheSeasonPreset":0,"InfectionElement":0,"Modifiers":[],"LobbyDescription":"","IsInfected":false}
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:35.536 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:35.537 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
15:40:35.537 INFO 12756 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
15:40:35.537 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:319] GameSelectionControllerBase:RemoveAllDelegates()
15:40:37.591 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 1
15:40:37.591 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:602] Toggled deck edit mode. New status 1
15:40:39.759 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:580] Attempting to toggle inventory.
15:40:39.759 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:593] Can toggle because of correct sections. New inventory state:1
15:40:39.759 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:388] Toggling show inventory: 1
15:40:42.213 INFO 12756 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:21] Processing inventory card click on index 370 with handler add_to_deck
15:40:42.214 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:636] Card added to deck. Collection index:370
15:40:42.214 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:697] Added card with collectionindex: 370 to deck slot: 10
15:40:42.214 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:202] Updating cover card
15:40:43.401 INFO 12756 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:21] Processing inventory card click on index 361 with handler add_to_deck
15:40:43.402 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:636] Card added to deck. Collection index:361
15:40:43.402 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:697] Added card with collectionindex: 361 to deck slot: 11
15:40:43.402 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:202] Updating cover card
15:40:44.367 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to DeckControl
15:40:44.367 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'GameWizard'
15:40:44.367 ERROR 12756 src\networking\connect.rs:212 proxy::networking::connect BUG, this should never happen, packet is already expected
15:40:44.368 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:44.368 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 2, state: 1, map: RPvEOnePlayer }
15:40:44.370 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:369] OnSectionClose...MatchMakingController...
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...0
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'DeckControl'
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/deck_control_v2/pg_deck_control_v2_view.xml)
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/deck_control_v2/pg_deck_control_v2_view.xml
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #0
15:40:44.371 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:90] Creating view bf1/ui/dialogs/pregame/deck_control_v2/pg_deck_control_v2_view.xml
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:44.449 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:100] ScreenFlow:Reset() #0
15:40:44.932 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
15:40:44.932 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:602] Toggled deck edit mode. New status 0
15:40:44.933 DEBUG 16112 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_SaveDeckForCharacterRMC sending msg: 0x7:1a SaveDeckForCharacterRMC { deck: 117, new_name: "test 2", cards: [DeckCard { guid: 25007, position: 0 }, DeckCard { guid: 24690, position: 1 }, DeckCard { guid: 24696, position: 2 }, DeckCard { guid: 24687, position: 3 }, DeckCard { guid: 24725, position: 4 }, DeckCard { guid: 24527, position: 5 }, DeckCard { guid: 24982, position: 6 }, DeckCard { guid: 24692, position: 7 }, DeckCard { guid: 24698, position: 8 }, DeckCard { guid: 24772, position: 9 }, DeckCard { guid: 24763, position: 10 }], cover_card: 25007, pool: 1, limited_pool_id: 0 }
15:40:45.109 DEBUG 16112 from_server\src\pregame.rs:81 from_server::pregame::PreGameMessage_SaveDeckForCharacterRMR received msg: 0x7:41 SaveDeckForCharacterRMR { error: 0, guid: 117, level: 66, tags: {} }
15:40:45.110 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:642] Deck cover card changed
15:40:45.110 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:202] Updating cover card
15:40:45.110 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:922] OnCurrentDeckChanged...MatchmakingController...
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'DeckControl'
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'GameWizard'
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
15:40:46.274 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:26] MatchmakingController...Initialize
15:40:46.283 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:46.283 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 4, state: 1, map: RPvEOnePlayer }
15:40:46.284 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:64] ...already initialized, check for correct display...
15:40:46.284 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...1
15:40:46.294 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_SetSpeedRunRMR received msg: 0x8:4c SetSpeedRunRMR
15:40:46.295 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
15:40:47.414 ERROR 12756 src\networking\connect.rs:212 proxy::networking::connect BUG, this should never happen, packet is already expected
15:40:47.423 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_PlayerReadyStatusChangedRMC sending msg: 0x8:47 PlayerReadyStatusChangedRMC { deck: 117, match_id: 62, ready: true }
15:40:47.554 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_PlayerReadyStatusChangedRMR received msg: 0x8:5d PlayerReadyStatusChangedRMR { error: 0 }
15:40:47.554 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 62, map: RPvEOnePlayer, pvp: false, team1: [Some(MatchPlayer { id: 849, name: "Weak1ings", deck_name: "test 2", cover_card: 4051, ready: true, pve_level: 3, elo: 0, deck_level: 66 })], team2: [] }
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:146] Player toggled ready status. New status 1
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:256] Attempt at toggling deckbar status. New status 0
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:259] Toggled deckbar active status. New status 0
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:598] Did not toggle deck edit mode
15:40:47.557 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:156] Updating decks button. New status 0
15:40:47.558 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:183] Updating new deck button
15:40:47.558 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:169] Updating lock deck button
15:40:47.558 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:909] OnPlayerReadyStateChanged...MatchmakingController...
15:40:47.560 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:47.560 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:47.560 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
15:40:47.561 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:47.563 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
15:40:47.563 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
15:40:47.563 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
15:40:47.563 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
15:40:47.563 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:1138]  infos = {"MapOfTheSeasonPreset":0,"InfectionElement":0,"Modifiers":[],"LobbyDescription":"","IsInfected":false}
15:40:48.745 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:1019] Starting Match...
15:40:48.746 DEBUG 16112 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_StartCustomGameRMC sending msg: 0x8:3d StartCustomGameRMC { match_id: 62 }
15:40:48.922 INFO 16112 src\networking\connect.rs:694 proxy::networking::connect Connecting to match server ...
15:40:48.922 TRACE 16112 src\networking\connect.rs:483 proxy::networking::connect connecting to: 162.55.91.56:7504 (162.55.91.56:7504)
15:40:48.922 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_StartCustomGameRMR received msg: 0x8:53 StartCustomGameRMR { error: 0 }
15:40:49.178 INFO 16112 src\networking\connect.rs:531 proxy::networking::connect connected TlsStream { io: PollEvented { io: Some(TcpStream { addr: 192.168.50.213:51134, peer: 162.55.91.56:7504, socket: 19156 }) }, session: ClientConnection, state: Stream }
15:40:49.178 INFO 16112 src\networking\connect.rs:715 proxy::networking::connect ... successfully connected ot match server!
15:40:49.178 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RegisterCharacterForGame sending msg: 0x5:8 RegisterCharacterForGame { session: 96, token: 11517649050824896728 }
15:40:49.178 DEBUG 16112 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CharacterForMatchCompleteNotification received msg: 0x8:c CharacterForMatchCompleteNotification { match_id: 62, map: RPvEOnePlayer, game_server_ip: 945502114, game_server_port: 7504, team: Team1, seed: 25130, team_id: 0, team_elo_rating: 0, players: [Player { id: 849, name: "Weak1ings", team: Team1, color: 1, deck: Deck { id: 117, name: "test 2", pool: 1, limited_pool_id: 0, cards: [DeckCard { id: 25007, position: 0, card: Card { id: 25007, card: "Teststrike", upgrades: 0, charges: 0 } }, DeckCard { id: 24690, position: 1, card: Card { id: 24690, card: "Viridya", upgrades: 3, charges: 3 } }, DeckCard { id: 24696, position: 2, card: Card { id: 24696, card: "Viridya!", upgrades: 0, charges: 0 } }, DeckCard { id: 24687, position: 3, card: Card { id: 24687, card: "Promise of Life", upgrades: 3, charges: 3 } }, DeckCard { id: 24725, position: 4, card: Card { id: 24725, card: "Promise of Life", upgrades: 3, charges: 3 } }, DeckCard { id: 24527, position: 5, card: Card { id: 24527, card: "Dreadcharger", upgrades: 3, charges: 3 } }, DeckCard { id: 24982, position: 6, card: Card { id: 24982, card: "Bandit Hut", upgrades: 3, charges: 3 } }, DeckCard { id: 24692, position: 7, card: Card { id: 24692, card: "Ravenheart", upgrades: 3, charges: 3 } }, DeckCard { id: 24698, position: 8, card: Card { id: 24698, card: "Ravenheart!", upgrades: 0, charges: 0 } }, DeckCard { id: 24772, position: 9, card: Card { id: 24772, card: "Satanael", upgrades: 3, charges: 3 } }, DeckCard { id: 24763, position: 10, card: Card { id: 24763, card: "Satanael", upgrades: 3, charges: 3 } }], cover_card: Card { id: 25007, card: "Teststrike", upgrades: 0, charges: 0 }, level: 66 }, character: CNetWorldPlayerCompressedVO { guid: 849, gold: 0, name: "Weak1ings", portrait: 5003, border: 0, texture: 0, title: 0, is_gm: false, state: 1, location: 4, map: RPvEOnePlayer, experience: 3088, elo: 0, highest_elo: 0 } }] }
15:40:49.180 INFO 12756 ???:??? PG (PREGAME) OnCharacterForMatchComplete triggered MatchID: 62
15:40:49.180 INFO 12756 ???:??? NETWORK ResetSequences triggered old MatchID: 0  new MatchID: 62
15:40:49.183 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatChannelRMR received msg: 0x6:1c JoinChatChannelRMR
15:40:49.194 DEBUG 16112 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatChannelRMR received msg: 0x6:1c JoinChatChannelRMR
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:114] OnMatchStarted...GameSection...
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:127] ... switch to matchmaking on match!
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:101] Can't adopt GroupleaderMapInfo. I'm the groupleader!
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml)
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:59] Screen is already active, do nothing: bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
15:40:49.201 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:133] OnStartTimer...GameSection...
15:40:49.212 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:18] Created new anonymous task
15:40:49.212 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:49.212 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:49.212 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
15:40:49.213 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:49.215 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
15:40:49.215 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:167] UpdateButton...State: Hide
15:40:49.215 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:169] ...Hide.
15:40:49.216 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:49.700 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:50.697 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:51.700 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:52.201 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task.lub:14] Anonymous timed task was destroyed.
15:40:52.696 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:53.698 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
15:40:53.698 INFO 12756 ???:??? UI (Lua) [=?]: [pg_game_section.lub:171] ...loading match!
15:40:53.698 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'GameWizard'
15:40:53.709 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:53.709 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 2, state: 1, map: RPvEOnePlayer }
15:40:53.709 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:369] OnSectionClose...MatchMakingController...
15:40:53.709 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...0
15:40:53.709 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
15:40:53.709 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:593] DeckBar isn't active, cant change Edit Mode in inactive state
15:40:53.724 INFO 12756 ???:??? Pathing Destroy Pathing Engine
15:40:53.726 INFO 12756 ???:??? NETWORK PG_StartMatch triggered MatchID: 62
15:40:53.732 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:53.732 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 1, state: 1, map: RPvEOnePlayer }
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionLog
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorGeneratorTypeChange
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
15:40:53.735 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorPlayerLeft
15:40:53.748 INFO 12756 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
15:40:53.749 INFO 12756 ???:??? Pathing.Manager CPathManagerConstructed
15:40:53.787 INFO 12756 ???:??? MGResourceLayoutSkirmish Adjusted setting: PathMin: 12.000, PathMax: 17.000, Border: 128
15:40:53.788 INFO 12756 ???:??? Pathing Destroy Pathing Engine
15:40:53.820 INFO 12756 ???:??? MapGenerator processing voronoi partition took 31 msec
15:40:53.820 INFO 12756 ???:??? MGResourceLayoutSkirmish Adjusted setting: PathMin: 12.000, PathMax: 17.000, Border: 128
15:40:53.821 INFO 12756 ???:??? MapGenerator creating resource distribution took 0 msec
15:40:53.831 INFO 12756 ???:??? MapGenerator shaping and growing took 0 msec
15:40:53.837 INFO 12756 ???:??? HeightMapShaper Setting Territory Height Levels took 0 msec
15:40:53.844 INFO 12756 ???:??? HeightMapShaper Creating Routes took 0 msec
15:40:53.922 INFO 12756 ???:??? HeightMapShaper Set HeightLevels for Routes took 78 msec
15:40:53.951 INFO 12756 ???:??? HeightMapShaper Blurring walkable height map positions took 31 msec
15:40:53.979 INFO 12756 ???:??? HeightMapShaper Creating Blocking Areas took 32 msec
15:40:53.983 INFO 12756 ???:??? HeightMapShaper Adjusting Blocking Areas took 0 msec
15:40:53.983 INFO 12756 ???:??? MapGenerator Creating pathes and routes took 157 msec
15:40:53.995 INFO 12756 ???:??? MapGenerator attaching cliffs took 15 msec
15:40:54.004 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.004 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.049 INFO 12756 ???:??? Prefabs No suitable cliff prefab found for Cliff_Horizontal, size 7
15:40:54.049 INFO 12756 ???:??? Prefabs No suitable cliff prefab found for Cliff_Horizontal, size 5
15:40:54.049 INFO 12756 ???:??? Prefabs No suitable cliff prefab found for Cliff_Horizontal, size 6
15:40:54.049 INFO 12756 ???:??? Prefabs No suitable cliff prefab found for Cliff_Horizontal, size 8
15:40:54.057 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000842 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000844 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000860 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000861 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000862 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000842 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000844 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000860 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000861 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000862 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000863 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000840 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000845 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000854 enabled by class
15:40:54.057 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000863 enabled by class
15:40:54.057 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.073 WARN 12756 ???:??? MGCreepRuleSet Not enough different creep entities defined for class air_defense to fit desired variance of 1 to level 2
15:40:54.073 WARN 12756 ???:??? MGCreepRuleSet Not enough different creep entities defined for class air_defense to fit desired variance of 1 to level 2
15:40:54.073 WARN 12756 ???:??? MGCreepRuleSet Not enough different creep entities defined for class air_defense to fit desired variance of 1 to level 2
15:40:54.073 INFO 12756 ???:??? MapGenerator positioning props took 78 msec
15:40:54.073 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.073 INFO 12756 ???:??? MapGenerator positioning normalmappatches took 0 msec
15:40:54.074 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.444 INFO 12756 ???:??? CMGFractalGenerator creating simplex noise took 360 msec
15:40:54.560 INFO 12756 ???:??? CMGTextureGenerator doing fractal texturing took 485 msec
15:40:54.563 INFO 12756 ???:??? CMGTextureGenerator doing prop texturing took 0 msec
15:40:54.592 INFO 12756 ???:??? CMGTextureGenerator doing cliff texturing took 16 msec
15:40:54.603 INFO 12756 ???:??? CMGTextureGenerator creating terrain blending took 16 msec
15:40:54.669 INFO 12756 ???:??? MapGenerator creating textures took 594 msec
15:40:54.670 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.671 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.671 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.671 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.710 INFO 12756 ???:??? MGMiniMapPainter painting minimap took 47 msec
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionLog
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorGeneratorTypeChange
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
15:40:54.711 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
15:40:54.712 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorPlayerLeft
15:40:54.725 INFO 12756 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
15:40:54.726 INFO 12756 ???:??? Pathing.Manager CPathManagerConstructed
15:40:54.765 INFO 12756 ???:??? MGResourceLayoutSkirmish Adjusted setting: PathMin: 12.000, PathMax: 17.000, Border: 128
15:40:54.765 INFO 12756 ???:??? MGMapCreator setting up Log took 47 msec
15:40:54.771 INFO 12756 ???:??? MGMapCreator setting up world took 0 msec
15:40:54.788 INFO 12756 ???:??? MGMapCreator texturing world took 31 msec
15:40:54.788 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:54.807 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:55.256 INFO 12756 ???:??? MGMapCreator Creating cliffs took 437 msec
15:40:55.326 INFO 12756 ???:??? MGMapCreator Creating GD entities took 78 msec
15:40:55.327 INFO 12756 ???:??? MGMapCreator creating mappatches took 0 msec
15:40:55.328 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:55.583 INFO 12756 ???:??? MGMapCreator creating water took 250 msec
15:40:55.584 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:55.585 INFO 12756 ???:??? MGMapCreator creating sound zones took 16 msec
15:40:55.585 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:56.006 INFO 12756 ???:??? MGMapCreator marking block bits took 422 msec
15:40:56.010 INFO 12756 ???:??? MGMapCreator generating aviatic heightmap took 0 msec
15:40:56.010 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000842 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000844 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000860 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000861 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000862 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000842 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000844 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000860 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000861 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000862 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000863 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000840 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000845 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000854 enabled by class
15:40:56.010 INFO 12756 ???:??? MGCreepCatalog Unique-settings for entity 2000863 enabled by class
15:40:56.198 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:56.248 DEBUG 12756 src\patches\map_presets.rs:96 proxy::patches::map_presets LAYOUT: ./bf1/map/randommaps/Frostland/
15:40:56.258 INFO 12756 ???:??? MGMapCreator saving map C:\Users\rustinc\Documents\BattleForge\randommap/GeneratedMap.map took 250 msec
15:40:56.291 INFO 12756 ???:??? Pathing Destroy Pathing Engine
15:40:56.296 INFO 12756 ???:??? MapGenerator creating map file and copying structures took 1625 msec
15:40:56.296 INFO 12756 ???:??? MapGenerator Whole map generation took 2500 msec
15:40:56.297 INFO 12756 src\patches\changes.rs:704 proxy::patches::changes Created Map C:\Users\rustinc\Documents\BattleForge\randommap/GeneratedMap.map (3838705681) with ResMap id 101, Seed 25130 and Difficulty 5!
15:40:56.360 TRACE 12756 src\patches\pvp_match.rs:212 proxy::patches::pvp_match 'C:\Users\rustinc\Documents\BattleForge\randommap\GeneratedMap\config\map_settings.json': Not found
15:40:56.360 TRACE 12756 src\patches\pvp_match.rs:287 proxy::patches::pvp_match V1(JsonMapConfigV1 { map_end_tick: None, hide_pvp_map_end_timer: None, hide_pvp_map_score: None, on_draw_add_looping_extension_ticks: None, perform_draw_check_at_remaining_ticks_before_end: None, extension_ticks: None })
15:40:56.360 WARN 12756 ???:??? gd log "[%i] loading map: %s"
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionLog
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorGeneratorTypeChange
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:783 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
15:40:56.361 TRACE 12756 src\patches\lua_functions\gds.rs:792 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorPlayerLeft
15:40:56.401 INFO 12756 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
15:40:56.401 INFO 12756 ???:??? Pathing.Manager CPathManagerConstructed
15:40:56.833 DEBUG 12756 src\patches\pvp_match.rs:301 proxy::patches::pvp_match default? true
15:40:56.833 DEBUG 12756 src\patches\pvp_match.rs:309 proxy::patches::pvp_match timer shown? true
15:40:56.833 DEBUG 12756 src\patches\pvp_match.rs:317 proxy::patches::pvp_match score shown? true
15:40:56.833 DEBUG 12756 src\patches\pvp_match.rs:342 proxy::patches::pvp_match match extending disable true
15:40:56.849 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 0, last_rng: 0, barrier_modules: 0, barrier_sets: 0, buildings: 4AA2DE18, figures: 83C6267C, objects: 242BE869, players: 0, power_slots: D149956A, projectiles: 0, map_scripts: 0, squads: E83E5AFE, token_slots: E6E24952, turrets: 0, world: 6224688B, config_crcs: [F0CE549B, 41C006B8, 63A52C01, 92C9C2B3, B6B33C05] }
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) --- Logging map CRCs ---
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uBarrierModuleCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uBarrierSetCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uBuildingCRC: 4aa2de18
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uFigureCRC: 83c6267c
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uObjectCRC: 242be869
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uPlayerCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uPowerSlotCRC: d149956a
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uProjectileCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uScriptingCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uSquadCRC: e83e5afe
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uTokenSlotCRC: e6e24952
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uTurretCRC: 0
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) uWorldCRC: 6224688b
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) AssetCRC #1: f0ce549b
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) AssetCRC #2: 41c006b8
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) AssetCRC #3: 63a52c01
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) AssetCRC #4: 92c9c2b3
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) AssetCRC #5: b6b33c05
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) Combined CRC: 11881851
15:40:56.849 INFO 12756 ???:??? PG (PREGAME) --- End of logging map CRCs ---
15:40:56.903 INFO 12756 ???:??? VisManager Loaded 4 different playercolors.
15:40:56.903 INFO 12756 ???:??? VisManager Loaded 5 Flame Locator mappings
15:40:56.903 INFO 12756 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
15:40:56.906 INFO 12756 ???:??? CVisSoundWorldPlugin Loaded 3 Reverb settings, defaults to bf1/sound/ir_presets/default.ifr with dry level 0.030000
15:40:56.926 INFO 12756 ???:??? VisMain Starting Build Scene...
15:40:58.484 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
15:40:58.484 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
15:40:58.484 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
15:40:58.485 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
15:40:58.487 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
15:40:58.487 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:648] DeckBarController:OnIngame
15:40:58.487 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
15:40:58.487 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:593] DeckBar isn't active, cant change Edit Mode in inactive state
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_chatwindow_controller.lub:342] ChatWindowController:OnIngame()
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_chatwindow_controller.lub:342] ChatWindowController:OnIngame()
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:128] Set ExitBtnState 1
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:138] Set SectionsPushButton state 1
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:138] Set SectionsPushButton state 1
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:147] Change contacts button icon
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 0
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:127] GameWidget is ParentView
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:136] HidePregameSections
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
15:40:58.488 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:432] Match initialized
15:40:58.496 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:58.496 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 5, state: 1, map: RPvEOnePlayer }
15:40:58.507 DEBUG 16112 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
15:40:58.507 DEBUG 16112 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_ChangeCharacterLocationStateRMC sending msg: 0x4:80 ChangeCharacterLocationStateRMC { location: 5, state: 2, map: RPvEOnePlayer }
15:40:58.529 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_MapCompleteLoadedAction sending msg: 0x5:e MapCompleteLoadedAction { map_cluster: 0, map_file_id: 120, crc: 294131793, random_map_crc: 3838705681 }
15:40:58.660 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_GameStartNotification received msg: 0x5:4 GameStartNotification { match_id: 62 }
15:40:58.663 INFO 12756 ???:??? PG (PREGAME) OnGameStart... game ID = 62
15:40:58.866 TRACE 12756 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal 'RvERandomMapsGoal1' for player: 'PL_PLAYER1'
15:40:58.868 TRACE 12756 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal 'RvERandomMapsGoal2' for player: 'PL_PLAYER1'
15:41:01.128 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3000706, player: 2243, card_position: 5, tag: 3328386, played_count: 16, x: 350.98923, y: 469.0688, barrier_to_mount: 0 } }
15:41:01.274 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 27, step: 26, command: ProduceSquad { card: 3000706, player: 2243, card_position: 5, tag: 3328386, played_count: 16, x: 350.98923, y: 469.0688, barrier_to_mount: 0 } }
15:41:01.769 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1E, last_rng: 0, simple_ida_crc: 6DD4B726 }
15:41:01.770 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 8257735057850063900, position_hash: 3552067900880962436, power_hash: 12260607767852866308 }, rounded: StateInfoHashes { health_hash: 8064871070763394851, position_hash: 13266649104937092792, power_hash: 8561504359593436044 }, figures: 158, entities: 2279, step: 30 } } } }
15:41:01.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 30, data: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 38, 183, 212, 109, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 5, 0, 0, 0, 155, 84, 206, 240, 184, 6, 192, 65, 1, 44, 165, 99, 179, 194, 201, 146, 5, 60, 179, 182] }
15:41:03.342 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 346.01205, y: 397.99524, barrier_to_mount: 0 } }
15:41:03.476 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 50, step: 48, command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 346.01205, y: 397.99524, barrier_to_mount: 0 } }
15:41:04.767 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3C, last_rng: 4, simple_ida_crc: E832D7E8 }
15:41:05.279 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 337.3285, y: 332.15024, barrier_to_mount: 0 } }
15:41:05.475 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 71, step: 68, command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 337.3285, y: 332.15024, barrier_to_mount: 0 } }
15:41:07.684 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 259.43515, y: 341.72665, barrier_to_mount: 0 } }
15:41:07.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 5A, last_rng: 2, simple_ida_crc: A6A9EF90 }
15:41:07.879 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 96, step: 92, command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 259.43515, y: 341.72665, barrier_to_mount: 0 } }
15:41:09.208 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 271.52066, y: 402.90317, barrier_to_mount: 0 } }
15:41:09.373 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 112, step: 107, command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 271.52066, y: 402.90317, barrier_to_mount: 0 } }
15:41:10.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 78, last_rng: 4, simple_ida_crc: CEB26C3 }
15:41:13.449 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: TokenSlotBuild { player: 2243, slot: 2082, monument: 1 } }
15:41:13.675 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 156, step: 150, command: TokenSlotBuild { player: 2243, slot: 2082, monument: 1 } }
15:41:13.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 96, last_rng: 2, simple_ida_crc: 6167E95E }
15:41:13.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 5286143800940217281, position_hash: 2899913571694177649, power_hash: 2123819189594132522 }, rounded: StateInfoHashes { health_hash: 6078586013991906592, position_hash: 13594708971935420551, power_hash: 2609316058700915788 }, figures: 162, entities: 2271, step: 150 } } } }
15:41:13.767 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 150, data: [2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 94, 233, 103, 97, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:41:16.767 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: B4, last_rng: 3, simple_ida_crc: 8AFD3FE1 }
15:41:18.095 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 365.1064, y: 466.4273, barrier_to_mount: 0 } }
15:41:18.279 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 203, step: 196, command: ProduceSquad { card: 2004051, player: 2243, card_position: 0, tag: 2004051, played_count: 1, x: 365.1064, y: 466.4273, barrier_to_mount: 0 } }
15:41:19.767 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: D2, last_rng: 4, simple_ida_crc: 2D19DD41 }
15:41:20.298 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2298, spell: 2040054, target: CGdTarget { version: 1, targets: [Target { category: 19, sub_category: 0, uid: 2081, x: 360.89563, y: 475.3913 }] } } }
15:41:20.472 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 226, step: 218, command: CastSpellEntity { player: 2243, source: 2298, spell: 2040054, target: CGdTarget { version: 1, targets: [Target { category: 19, sub_category: 0, uid: 2081, x: 360.89563, y: 475.3913 }] } } }
15:41:22.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: F0, last_rng: 2, simple_ida_crc: 76B80ACE }
15:41:25.764 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 10E, last_rng: 1, simple_ida_crc: 59422657 }
15:41:25.764 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 10588286911490690892, position_hash: 2382898091829479775, power_hash: 552456899603491564 }, rounded: StateInfoHashes { health_hash: 13810370313200014101, position_hash: 13554325345100586936, power_hash: 14364438057028520817 }, figures: 130, entities: 2240, step: 270 } } } }
15:41:25.774 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 270, data: [1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 87, 38, 66, 89, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:41:26.383 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: TokenSlotBuild { player: 2243, slot: 2083, monument: 1 } }
15:41:26.572 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 288, step: 279, command: TokenSlotBuild { player: 2243, slot: 2083, monument: 1 } }
15:41:27.325 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupLootTarget { player: 2243, squads: [2292], loot_target: 2062 } }
15:41:27.470 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 298, step: 288, command: GroupLootTarget { player: 2243, squads: [2292], loot_target: 2062 } }
15:41:27.567 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: PlayerLoot { account: 849, loot_target: 2062, map_loot_id: 194 } }
15:41:27.773 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 302, step: 291, command: PlayerLootResult { account: 849, loot_target: 2062, gold: [(849, 23)] } }
15:41:28.156 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2292], positions: [(253.01027, 342.1766)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:28.379 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 309, step: 297, command: GroupGoto { player: 2243, squads: [2292], positions: [(253.01027, 342.1766)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:28.508 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
15:41:28.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 12C, last_rng: 0, simple_ida_crc: AA6E90E5 }
15:41:28.993 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2292], positions: [(267.10803, 368.12616)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:29.177 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 318, step: 305, command: GroupGoto { player: 2243, squads: [2292], positions: [(267.10803, 368.12616)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:31.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 14A, last_rng: 4, simple_ida_crc: 9A971D09 }
15:41:33.630 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2286, 2292, 2295], positions: [(352.5036, 399.36923)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:33.771 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 365, step: 351, command: GroupGoto { player: 2243, squads: [2286, 2292, 2295], positions: [(352.5036, 399.36923)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:34.516 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupLootTarget { player: 2243, squads: [2286, 2292, 2295], loot_target: 2061 } }
15:41:34.680 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 375, step: 360, command: GroupLootTarget { player: 2243, squads: [2286, 2292, 2295], loot_target: 2061 } }
15:41:34.768 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 168, last_rng: 4, simple_ida_crc: 136406F3 }
15:41:34.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: PlayerLoot { account: 849, loot_target: 2061, map_loot_id: 193 } }
15:41:34.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: PlayerLoot { account: 849, loot_target: 2061, map_loot_id: 193 } }
15:41:34.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: PlayerLoot { account: 849, loot_target: 2061, map_loot_id: 193 } }
15:41:34.972 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 379, step: 363, command: PlayerLootResult { account: 849, loot_target: 2061, gold: [(849, 38)] } }
15:41:36.819 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupKillEntity { player: 2243, entities: [2286, 2292, 2295] } }
15:41:36.972 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 400, step: 383, command: GroupKillEntity { player: 2243, entities: [2286, 2292, 2295] } }
15:41:37.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 186, last_rng: 0, simple_ida_crc: F4604D85 }
15:41:37.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 5945571771336305360, position_hash: 488399909234726598, power_hash: 879481578557776178 }, rounded: StateInfoHashes { health_hash: 8577741753654366350, position_hash: 10642417674679514148, power_hash: 10475887178996766298 }, figures: 97, entities: 2204, step: 390 } } } }
15:41:37.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 390, data: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 133, 77, 96, 244, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:41:38.767 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: ProgressEvents { player: 2243, events: [(10, 3)] } } }
15:41:40.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1A4, last_rng: 2, simple_ida_crc: ED9043E7 }
15:41:43.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1C2, last_rng: 0, simple_ida_crc: A6A4D175 }
15:41:46.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1E0, last_rng: 3, simple_ida_crc: ED0B36D1 }
15:41:49.652 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2283], positions: [(344.63492, 469.3811)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:49.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1FE, last_rng: 0, simple_ida_crc: 85B7DC06 }
15:41:49.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 5945571771336305360, position_hash: 488399909234726598, power_hash: 11575238321050691895 }, rounded: StateInfoHashes { health_hash: 8577741753654366350, position_hash: 10642417674679514148, power_hash: 15537679045370103317 }, figures: 97, entities: 2204, step: 510 } } } }
15:41:49.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 510, data: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 6, 220, 183, 133, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:41:49.876 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 530, step: 512, command: GroupGoto { player: 2243, squads: [2283], positions: [(344.63492, 469.3811)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:41:51.487 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupKillEntity { player: 2243, entities: [2283] } }
15:41:51.675 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 549, step: 530, command: GroupKillEntity { player: 2243, entities: [2283] } }
15:41:52.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 21C, last_rng: 1, simple_ida_crc: 88468CAE }
15:41:52.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: ProgressEvents { player: 2243, events: [(10, 1)] } } }
15:41:55.766 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 23A, last_rng: 0, simple_ida_crc: B11296B3 }
15:41:58.372 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 4, x: 252.55385, y: 342.35486, barrier_to_mount: 0 } }
15:41:58.510 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
15:41:58.579 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 619, step: 599, command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 4, x: 252.55385, y: 342.35486, barrier_to_mount: 0 } }
15:41:58.767 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 258, last_rng: 3, simple_ida_crc: B8FA5AD7 }
15:42:01.765 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 276, last_rng: 2, simple_ida_crc: AAAB3214 }
15:42:01.766 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 7249652557889704248, position_hash: 15516159804774904715, power_hash: 5098655286199946441 }, rounded: StateInfoHashes { health_hash: 12722400809015099910, position_hash: 11211900861548800417, power_hash: 4313376154987365534 }, figures: 95, entities: 2202, step: 630 } } } }
15:42:01.770 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 630, data: [2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 20, 50, 171, 170, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:42:04.794 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 294, last_rng: 0, simple_ida_crc: F64DFC9F }
15:42:07.832 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 2B2, last_rng: 2, simple_ida_crc: CE1CF85 }
15:42:10.858 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 2D0, last_rng: 0, simple_ida_crc: FD076159 }
15:42:13.833 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 2EE, last_rng: 1, simple_ida_crc: BC947F6D }
15:42:13.833 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 9174624765931822184, position_hash: 1218680249376674060, power_hash: 7295506460763736676 }, rounded: StateInfoHashes { health_hash: 16088629786884432161, position_hash: 3517068912218351100, power_hash: 12798826939327153113 }, figures: 94, entities: 2201, step: 750 } } } }
15:42:13.839 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 750, data: [1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 109, 127, 148, 188, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:42:16.773 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 30C, last_rng: 1, simple_ida_crc: 53304524 }
15:42:17.910 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 255, x: 255.11203, y: 332.23074, barrier_to_mount: 0 } }
15:42:18.072 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 815, step: 794, command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 255, x: 255.11203, y: 332.23074, barrier_to_mount: 0 } }
15:42:19.772 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 32A, last_rng: 4, simple_ida_crc: 9648BC07 }
15:42:19.926 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 255, x: 246.05, y: 328.65, barrier_to_mount: 0 } }
15:42:20.070 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 836, step: 814, command: ProduceSquad { card: 3001385, player: 2243, card_position: 9, tag: 3591209, played_count: 255, x: 246.05, y: 328.65, barrier_to_mount: 0 } }
15:42:21.031 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2300, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2300, x: 252.34999, y: 342.65 }] } } }
15:42:21.031 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2302, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2302, x: 255.15, y: 332.15 }] } } }
15:42:21.031 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2304, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2304, x: 246.05, y: 328.65 }] } } }
15:42:21.171 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 848, step: 825, command: CastSpellEntity { player: 2243, source: 2300, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2300, x: 252.34999, y: 342.65 }] } } }
15:42:21.171 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 849, step: 825, command: CastSpellEntity { player: 2243, source: 2302, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2302, x: 255.15, y: 332.15 }] } } }
15:42:21.171 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 850, step: 825, command: CastSpellEntity { player: 2243, source: 2304, spell: 3003122, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2304, x: 246.05, y: 328.65 }] } } }
15:42:22.774 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 348, last_rng: 2, simple_ida_crc: 27928D5A }
15:42:24.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: ProgressEvents { player: 2243, events: [(22, 6)] } } }
15:42:25.730 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(269.58777, 332.38364)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:25.772 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 366, last_rng: 0, simple_ida_crc: BF13F109 }
15:42:25.773 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 10071612352374227472, position_hash: 15935995139231242948, power_hash: 14274177254663297940 }, rounded: StateInfoHashes { health_hash: 10686133599486605629, position_hash: 13829373563441304392, power_hash: 7853205760881773971 }, figures: 120, entities: 2235, step: 870 } } } }
15:42:25.775 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 870, data: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 9, 241, 19, 191, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:42:25.878 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 898, step: 872, command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(269.58777, 332.38364)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:26.730 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(275.0517, 334.1352)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:26.878 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 909, step: 882, command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(275.0517, 334.1352)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:28.220 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(266.72092, 324.77606)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:28.372 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 925, step: 897, command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(266.72092, 324.77606)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:28.510 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
15:42:28.703 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(263.2724, 323.77216)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:28.772 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 384, last_rng: 0, simple_ida_crc: 3093CCD2 }
15:42:28.840 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupHoldPosition { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331] } }
15:42:28.877 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 931, step: 902, command: GroupGoto { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331], positions: [(263.2724, 323.77216)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:28.968 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 933, step: 903, command: GroupHoldPosition { player: 2243, squads: [2306, 2311, 2316, 2321, 2326, 2331] } }
15:42:30.906 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupKillEntity { player: 2243, entities: [2300, 2302, 2304, 2306, 2311, 2316, 2321, 2326, 2331] } }
15:42:31.069 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 955, step: 924, command: GroupKillEntity { player: 2243, entities: [2300, 2302, 2304, 2306, 2311, 2316, 2321, 2326, 2331] } }
15:42:31.774 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3A2, last_rng: 0, simple_ida_crc: AA0C33CE }
15:42:32.772 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: ProgressEvents { player: 2243, events: [(10, 9)] } } }
15:42:33.662 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2298, spell: 2040054, target: CGdTarget { version: 1, targets: [Target { category: 19, sub_category: 0, uid: 2079, x: 356.69565, y: 465.5913 }] } } }
15:42:33.877 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 984, step: 952, command: CastSpellEntity { player: 2243, source: 2298, spell: 2040054, target: CGdTarget { version: 1, targets: [Target { category: 19, sub_category: 0, uid: 2079, x: 356.69565, y: 465.5913 }] } } }
15:42:34.772 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3C0, last_rng: 3, simple_ida_crc: D60D2BDD }
15:42:37.776 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3DE, last_rng: 4, simple_ida_crc: 58E27544 }
15:42:37.776 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 9022670142545128918, position_hash: 6788201470673734842, power_hash: 15057802405039182368 }, rounded: StateInfoHashes { health_hash: 11801733743210209162, position_hash: 3554715223650999815, power_hash: 12417878994444404413 }, figures: 120, entities: 2226, step: 990 } } } }
15:42:37.780 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 990, data: [4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 68, 117, 226, 88, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:42:38.018 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 4, x: 254.17526, y: 338.9398, barrier_to_mount: 0 } }
15:42:38.170 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1028, step: 995, command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 4, x: 254.17526, y: 338.9398, barrier_to_mount: 0 } }
15:42:39.223 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 255, x: 249.05692, y: 330.57892, barrier_to_mount: 0 } }
15:42:39.372 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1041, step: 1007, command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 255, x: 249.05692, y: 330.57892, barrier_to_mount: 0 } }
15:42:40.776 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3FC, last_rng: 3, simple_ida_crc: 8CFFE16F }
15:42:41.240 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 255, x: 243.95, y: 322.35, barrier_to_mount: 0 } }
15:42:41.371 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1062, step: 1027, command: ProduceSquad { card: 3001348, player: 2243, card_position: 10, tag: 3656708, played_count: 255, x: 243.95, y: 322.35, barrier_to_mount: 0 } }
15:42:43.780 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 41A, last_rng: 1, simple_ida_crc: 599BB58B }
15:42:45.216 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2336, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2336, x: 254.45, y: 339.15 }] } } }
15:42:45.216 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2338, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2338, x: 248.84999, y: 330.75 }] } } }
15:42:45.216 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: CastSpellEntity { player: 2243, source: 2340, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2340, x: 243.95, y: 322.35 }] } } }
15:42:45.370 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1103, step: 1067, command: CastSpellEntity { player: 2243, source: 2336, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2336, x: 254.45, y: 339.15 }] } } }
15:42:45.370 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1104, step: 1067, command: CastSpellEntity { player: 2243, source: 2338, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2338, x: 248.84999, y: 330.75 }] } } }
15:42:45.370 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1105, step: 1067, command: CastSpellEntity { player: 2243, source: 2340, spell: 3003120, target: CGdTarget { version: 1, targets: [Target { category: 6, sub_category: 0, uid: 2340, x: 243.95, y: 322.35 }] } } }
15:42:46.775 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 438, last_rng: 2, simple_ida_crc: F4C0F8FD }
15:42:48.778 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: ProgressEvents { player: 2243, events: [(22, 6)] } } }
15:42:49.780 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 456, last_rng: 3, simple_ida_crc: 282BF5E9 }
15:42:49.780 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: SrAddition { data: StateHashes { state: StateInfo { exact: StateInfoHashes { health_hash: 9474966492233285989, position_hash: 4170396604996063183, power_hash: 9088146061611262252 }, rounded: StateInfoHashes { health_hash: 14274770138230579284, position_hash: 9172559897783278027, power_hash: 2462116671824814985 }, figures: 147, entities: 2262, step: 1110 } } } }
15:42:49.785 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_SyncCheckAction sending msg: 0x5:3 SyncCheckAction { map_cluster: 0, step: 1110, data: [3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 233, 245, 43, 40, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }
15:42:50.075 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(262.8342, 327.04645)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:50.269 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1155, step: 1116, command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(262.8342, 327.04645)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:51.015 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(282.43784, 328.28488)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:51.176 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1165, step: 1125, command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(282.43784, 328.28488)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:52.383 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(260.70435, 323.99734)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:52.570 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1180, step: 1139, command: GroupGoto { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367], positions: [(260.70435, 323.99734)], walk_mode: 2, run_or_orientation: 1, orientation: 0.0 } }
15:42:52.774 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 474, last_rng: 4, simple_ida_crc: E946E5C4 }
15:42:52.858 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: GroupHoldPosition { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367] } }
15:42:53.075 DEBUG 16112 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 62, sequence_number: 1186, step: 1144, command: GroupHoldPosition { player: 2243, squads: [2342, 2347, 2352, 2357, 2362, 2367] } }
15:42:55.910 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 492, last_rng: 4, simple_ida_crc: 8E5B8EA }
15:42:58.512 DEBUG 16112 sr_packets\src\from_game\extensions.rs:56 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
15:42:58.736 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:18] Created new anonymous task
15:42:58.744 INFO 12756 ???:??? UI (Lua) [=?]: [timed_task.lub:14] Anonymous timed task was destroyed.
15:42:58.773 TRACE 12756 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 4B0, last_rng: 4, simple_ida_crc: D41D3269 }
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:647] pg_exit_game
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:503] SectionBrowser:Exit...
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:542] SectionBrowser.Close
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: UIEditor
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: News
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_widget_controller.lub:61] Calling a deprecated Method: WidgetController:Close
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: BoosterContent
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: OpenedBooster
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Login
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: CompareProfile
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: CardReforge
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Season
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: ReplayPlayedCards
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: CommandBuilder
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: CardUpgrade
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Options
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: PlayersControlView
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: StoryBook
15:43:00.166 INFO 12756 ???:??? UI (Lua) [=?]: [pg_storybook_controller.lub:166] Close...StoryBookController...
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: DeckCreation
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: BoosterHistoryListEntry
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: DirectTrade
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: GameWizard
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:350] MatchmakingController...Close...
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:249] MatchmakingController:RemoveAllDelegates()
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...0
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Marketplace
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: DeckControl
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_widget_controller.lub:61] Calling a deprecated Method: WidgetController:Close
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: ShowDeck
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Mail
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Replays
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: BoosterHistory
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:544] Destroying Section: Forge
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:43] Inventory controller: Close
15:43:00.167 INFO 12756 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:115] Removing inventory controller delegates.
15:43:00.168 DEBUG 16112 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: PlayerSurrender { player: 2243 } }
15:43:00.168 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:56] PlayerListController...Close...
15:43:00.168 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:58] ...RemoveAllDelegates
15:43:00.168 INFO 12756 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:36] RemoveAllDelegates...PlayerListController...
15:43:00.170 INFO 12756 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:74] RemoveAllDelegates...NavigationController
15:43:00.171 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:139] Deck bar closed. Saving current deck and removing delegates
15:43:00.171 INFO 12756 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:83] Removing deck bar delegates.
15:43:00.172 INFO 12756 ???:??? UI (Lua) [=?]: [pg_widget_controller.lub:61] Calling a deprecated Method: WidgetController:Close
15:43:00.172 INFO 12756 ???:??? UI (Lua) [=?]: [pg_public_chatwindow_controller.lub:45] Successfully closed PublicChatWindowController.
15:43:00.172 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:118] PlayerLocationSearchController...Close...
15:43:00.172 INFO 12756 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:95] PlayerLocationSearchController...RemoveAllDelegates...
15:43:00.172 INFO 12756 ???:??? UI (Lua) [=?]: [pg_widget_controller.lub:61] Calling a deprecated Method: WidgetController:Close
15:43:00.173 INFO 12756 ???:??? UI (Lua) [=?]: [pg_private_chatwindow_controller.lub:38] Successfully closed PrivateChatWindowController.
15:43:00.173 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:194] EventWindowController...Close...
15:43:00.173 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:196] ...RemoveAllDelegates
15:43:00.173 INFO 12756 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:76] EventWindowController:RemoveAllDelegates()
15:43:00.176 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:225] Removing notification listener...
15:43:00.176 INFO 12756 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:227] Notification listener removed!
15:43:00.176 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:126] Removing packet listener...
15:43:00.176 INFO 12756 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:128] Packet listener removed!
15:43:00.176 INFO 12756 src\networking\connect.rs:305 proxy::networking::connect destroying
15:43:00.176 INFO 12756 src\networking\open_ssl_wrapper.rs:173 proxy::networking::open_ssl_wrapper disconnecting
15:43:00.176 ERROR 16112 src\networking\connect.rs:422 proxy::networking::connect FP->S no msg
15:43:00.177 INFO 12756 src\networking\open_ssl_wrapper.rs:188 proxy::networking::open_ssl_wrapper disconnected
15:43:00.177 INFO 12756 src\networking\connect.rs:307 proxy::networking::connect destroyed
15:43:00.177 ERROR 16112 src\networking\connect.rs:404 proxy::networking::connect P->BF no msg
15:43:00.177 ERROR 16112 src\networking\connect.rs:570 proxy::networking::connect MP->S no msg