22:05:27.629 WARN 10916 src\lib.rs:162 proxy proxy from 2025-06-01T12:18:43.855979800Z is initializing
22:05:27.629 INFO 10916 src\lib.rs:231 proxy Rust data: 0x57f0f000 .. 0x57f1a5b4
22:05:27.629 INFO 10916 src\lib.rs:221 proxy Rust const VF tables: 0x57f1b000 .. 0x57f1b2a0
22:05:27.629 INFO 10916 src\lib.rs:211 proxy Rust VF tables: 0x57f1c000 .. 0x57f1ccd0
22:05:27.630 INFO 10916 src\patches\debug\not_a_game_talk.rs:19 proxy::patches::debug::not_a_game_talk not a game talk hooks initialized
22:05:27.630 TRACE 10916 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 }
22:05:27.630 INFO 10916 src\patches\debug\lua\script_actions.rs:177 proxy::patches::debug::lua::script_actions Scrip actions initialized
22:05:27.631 INFO 10916 src\patches\debug\lua.rs:105 proxy::patches::debug::lua lua tracing initialized
22:05:27.633 INFO 10916 src\patches\lua_functions.rs:48 proxy::patches::lua_functions lua hooks initialized
22:05:27.633 INFO 10916 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
22:05:27.635 INFO 10916 src\patches\lua_functions\gds.rs:50 proxy::patches::lua_functions::gds lua gds hooks initialized
22:05:27.635 INFO 10916 src\patches\lua_functions\cpg_card.rs:19 proxy::patches::lua_functions::cpg_card lua card hooks initialized
22:05:27.636 INFO 10916 src\patches\lua_functions\player.rs:22 proxy::patches::lua_functions::player lua player hooks initialized
22:05:27.637 INFO 10916 src\patches\lua_functions\widget\mod.rs:21 proxy::patches::lua_functions::widget lua widget hooks initialized
22:05:27.637 INFO 10916 src\patches\lua_functions\chat_channel.rs:34 proxy::patches::lua_functions::chat_channel chat channel hooks initialized
22:05:27.638 INFO 10916 src\patches\lua_functions\luabind.rs:22 proxy::patches::lua_functions::luabind Luabind hooks initialized
22:05:27.639 INFO 10916 src\patches\bugs\skip_broken_effects.rs:17 proxy::patches::bugs::skip_broken_effects 'bad' effects should not crash
22:05:27.640 INFO 10916 src\patches\bugs\spell_teleport_area.rs:21 proxy::patches::bugs::spell_teleport_area spell teleport area fix initialized
22:05:27.641 INFO 10916 src\patches\bugs\stampede.rs:9 proxy::patches::bugs::stampede Stampede fix initialized
22:05:27.644 TRACE 10916 src\patches\bugs\teleport_squad_to_entity.rs:22 proxy::patches::bugs::teleport_squad_to_entity teleport squad to entity fix initialized
22:05:27.644 TRACE 10916 src\patches\bugs\global_ability_on_entity.rs:18 proxy::patches::bugs::global_ability_on_entity global ability on entity fix initialized
22:05:27.651 INFO 10916 src\patches\changes\abilities\spell_blocker.rs:15 proxy::patches::changes::abilities::spell_blocker Ability AbilitySpellBlocker new param hooks initialized
22:05:27.653 INFO 10916 src\patches\changes\mind_control.rs:9 proxy::patches::changes::mind_control Mind control limit bypass initialized
22:05:27.653 INFO 10916 src\patches\changes\elements\card_enum.rs:22 proxy::patches::changes::elements::card_enum Card rendering initialized
22:05:27.655 INFO 10916 src\patches\changes\global_buffs.rs:31 proxy::patches::changes::global_buffs global buffs hooks initialized
22:05:27.655 INFO 10916 src\patches\changes\entity_buffs.rs:14 proxy::patches::changes::entity_buffs entity buffs hooks initialized
22:05:27.655 INFO 10916 src\patches\changes\half_orb.rs:39 proxy::patches::changes::half_orb half orbs hooks initialized
22:05:27.656 INFO 10916 src\patches\changes\map_crc.rs:13 proxy::patches::changes::map_crc Map CRC hooks initialized
22:05:27.656 INFO 10916 src\patches\changes\finished_maps.rs:18 proxy::patches::changes::finished_maps Finished maps hooks initialized
22:05:27.656 INFO 10916 src\patches\changes\new_spells.rs:26 proxy::patches::changes::new_spells New spells hooks initialized
22:05:27.657 INFO 10916 src\patches\changes\new_spell_conditions.rs:34 proxy::patches::changes::new_spell_conditions New spell condition hooks initialized
22:05:27.657 INFO 10916 src\patches\changes\context_menu_player.rs:15 proxy::patches::changes::context_menu_player Context menu player extension hooks initialized
22:05:27.658 INFO 10916 src\patches\changes\ability_ability_dependend_ability.rs:53 proxy::patches::changes::ability_ability_dependend_ability Ability AbilityDependendAbility new param hooks initialized
22:05:27.658 INFO 10916 src\patches\changes\ability_immunity.rs:36 proxy::patches::changes::ability_immunity Ability AbilityImmunity new param hooks initialized
22:05:27.658 INFO 10916 src\patches\changes\spell_condition_unit_cap.rs:22 proxy::patches::changes::spell_condition_unit_cap Spell Condition new param hooks initialized
22:05:27.659 INFO 10916 src\patches\changes\ability_dot.rs:17 proxy::patches::changes::ability_dot Ability DOT new param hooks initialized
22:05:27.659 INFO 10916 src\patches\changes\forge_map.rs:22 proxy::patches::changes::forge_map forge map selected
22:05:27.661 INFO 10916 src\patches\changes\hot_keys\replay.rs:23 proxy::patches::changes::hot_keys::replay Replay hooks initialized
22:05:27.662 INFO 10916 src\patches\changes\hot_keys.rs:63 proxy::patches::changes::hot_keys Hot key hooks initialized
22:05:27.663 INFO 10916 src\patches\changes\chat_commands.rs:58 proxy::patches::changes::chat_commands Chat commands hooks initialized
22:05:27.665 INFO 10916 src\patches\changes\void_return.rs:24 proxy::patches::changes::void_return void return hooks initialized
22:05:27.666 INFO 10916 src\patches\changes\building_spawn_animations.rs:41 proxy::patches::changes::building_spawn_animations construction helper hooks initialized
22:05:27.666 INFO 10916 src\patches\changes\dazed_immunity_addition.rs:13 proxy::patches::changes::dazed_immunity_addition Dazed immunity addition initialized
22:05:27.666 INFO 10916 src\patches\changes\max_heal_per_target.rs:18 proxy::patches::changes::max_heal_per_target max heal per target initialized
22:05:27.666 INFO 10916 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
22:05:27.667 INFO 10916 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
22:05:27.669 INFO 10916 src\patches\changes\community_map.rs:57 proxy::patches::changes::community_map community map hooks initialized
22:05:27.671 INFO 10916 src\patches\changes\selection_action_list.rs:27 proxy::patches::changes::selection_action_list Selection action list initialized
22:05:27.671 INFO 10916 src\patches\changes\loca_string_processing.rs:34 proxy::patches::changes::loca_string_processing Loca string processing initialized
22:05:27.672 INFO 10916 src\patches\changes\game_commands.rs:51 proxy::patches::changes::game_commands Game commands hooks initialized
22:05:27.672 INFO 10916 src\patches\changes\ability_set_duration.rs:19 proxy::patches::changes::ability_set_duration Ability effect holder duration hooks initialized
22:05:27.673 INFO 10916 src\patches\changes\spectating\mod.rs:69 proxy::patches::changes::spectating spectating hooks initialized
22:05:27.674 INFO 10916 src\patches\changes\friend_list.rs:9 proxy::patches::changes::friend_list Friend list patches initialized
22:05:27.675 INFO 10916 src\patches\on_tick.rs:28 proxy::patches::on_tick on tick hooks initialized
22:05:27.675 INFO 10916 src\patches\map_presets.rs:43 proxy::patches::map_presets tests hooks initialized
22:05:27.679 INFO 10916 src\patches\files_system.rs:67 proxy::patches::files_system base file system hooks initialized
22:05:27.679 INFO 10916 src\patches\files_system.rs:79 proxy::patches::files_system map file system hooks initialized
22:05:27.681 INFO 10916 src\patches\inventory\mod.rs:38 proxy::patches::inventory inventory hooks initialized
22:05:27.683 INFO 10916 src\networking\connect.rs:203 proxy::networking::connect connect disconnect hooks initialized
22:05:27.683 INFO 10916 src\networking\mod.rs:33 proxy::networking networking hooks initialized
22:05:27.683 WARN 10916 src\lib.rs:181 proxy Initialized successfully
22:05:27.990 WARN 10916 src\game_config\get_set.rs:107 proxy::game_config::get_set application:maxshader '"0"' is not a int
22:05:27.999 INFO 10916 ???:??? Direct3D 9 Start Scan
22:05:28.095 INFO 10916 ???:??? Direct3D 9 Found Adapter: NVIDIA GeForce GTX 1050
22:05:28.095 INFO 10916 ???:??? Direct3D 9 Support Hardware vertex processing
22:05:28.095 INFO 10916 ???:??? Direct3D 9 Support SM 3.0
22:05:28.095 INFO 10916 ???:??? Direct3D 9 End Scan
22:05:28.096 INFO 10916 ???:??? Direct3D 11 Start DXGI scan.
22:05:28.101 INFO 10916 ???:??? Direct3D 11 Found Adapter: NVIDIA GeForce GTX 1050
22:05:28.328 INFO 10916 ???:??? Direct3D 11 Supports Techlevel 11.0
22:05:28.328 INFO 10916 ???:??? Direct3D 11 End DXGI scan.
22:05:28.331 INFO 10916 ???:??? app BattleForge Final Build: 400052 (Retail branch)
22:05:28.331 INFO 10916 ???:??? app CPU GenuineIntel family 6 (P2, P3, Pentium-M, Athlon) detected.
22:05:28.331 WARN 10916 D:\Skylords\build\live\proxy\bf_utils\src\fs\pak_files.rs:121 bf_utils::fs::pak_files Loading paks from: '"base\\pak"' (base/pak/)
22:05:28.332 INFO 10916 bf_utils\src\fs\pak_files.rs:152 bf_utils::fs::pak_files loading files from: base\pak
22:06:06.021 INFO 10916 ???:??? app CPU clock speed is ~3392 MHz.
22:06:06.021 INFO 10916 ???:??? app CPU has 8 cores.
22:06:06.021 INFO 10916 ???:??? app CPU rating is 0 points.
22:06:06.021 INFO 10916 ???:??? app GFX rating is 0 points.
22:06:06.037 INFO 10916 ???:??? CreateDisplay Creating Display DirectX 11
22:06:06.100 INFO 10916 ???:??? Create Device Use Direct3D 11
22:06:06.106 INFO 10916 ???:??? GPU Vendor ID = 0x10de
22:06:06.106 INFO 10916 ???:??? GPU Device ID = 0x1c81
22:06:06.106 INFO 10916 ???:??? GPU Subsystem ID = 0x85d71043
22:06:06.106 INFO 10916 ???:??? GPU Revision = 0xa1
22:06:06.106 INFO 10916 ???:??? GPU Device description = NVIDIA GeForce GTX 1050
22:06:06.106 INFO 10916 ???:??? GPU Video Memory = 2057433088
22:06:06.106 INFO 10916 ???:??? GPU System Memory = 0
22:06:06.106 INFO 10916 ???:??? GPU Shared Memory = -2057498624
22:06:06.416 WARN 10916 ???:??? Direct3D 11 Create shader resource view : S_OK
22:06:08.577 TRACE 1204 src\patches\inventory\mod.rs:80 proxy::patches::inventory loading took: 3.7298044s
22:06:10.442 INFO 5800 src\patches\changes\affinities_highlighting.rs:52 proxy::patches::changes::affinities_highlighting highlight all cards differences took: 1.2276544s
22:06:10.638 INFO 5800 ???:??? PssSoundPipeline Loading soundpipeline:

 Version: 1
Number of Files:10185
22:06:11.016 INFO 5800 ???:??? PssSoundPipeline Loading soundpipeline bf1/sound/SoundPipeline_en.bin finished, 40422.23 seconds of music material in pipe.
22:06:11.078 INFO 5800 ???:??? CPssPhysicalRenderer Got Stereo speakers!
22:06:11.497 INFO 5800 ???:??? VisManager Loaded 4 different playercolors.
22:06:11.564 INFO 5800 ???:??? VisManager Loaded 5 Flame Locator mappings
22:06:11.591 INFO 5800 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
22:06:13.239 INFO 5800 ???:??? UI (Lua) [=?]: [datetime_utils.lub:11] Time locale set to: Polish_Poland.1252
22:06:13.349 INFO 5800 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:18] Created new anonymous task
22:06:13.349 INFO 5800 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:16] Restoring inventory card click handler to default
22:06:13.349 INFO 5800 ???:??? UI (Lua) [=?]: [card_double_click_context.lub:27] Setting inventory card click handler to 'add_to_deck'
22:06:13.376 INFO 5800 ???:??? UI (Lua) [=?]: [event_listener.lub:20] Register EventListener with id 55
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to Login
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'Login'
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/login/pg_login_view.xml)
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/login/pg_login_view.xml
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #0
22:06:13.891 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:83] Creating view bf1/ui/dialogs/pregame/login/pg_login_view.xml
22:06:13.997 INFO 5800 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:13] Created new task ReEnableLogin
22:06:14.019 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:06:14.019 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:06:14.019 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:06:14.020 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:06:14.020 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:06:14.020 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:06:14.020 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:06:14.021 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:06:18.567 INFO 5800 src\networking\connect.rs:257 proxy::networking::connect connecting
22:06:18.593 INFO 5800 src\networking\connect.rs:320 proxy::networking::connect forge_connect
22:06:19.100 INFO 5800 src\networking\connect.rs:359 proxy::networking::connect connected TlsStream { io: PollEvented { io: Some(TcpStream { addr: 192.168.74.144:51679, peer: 162.55.91.56:7401, socket: 13812 }) }, session: ClientConnection, state: Stream }
22:06:19.161 INFO 5800 src\networking\connect.rs:262 proxy::networking::connect fully connected
22:06:19.205 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:215] Adding notification listener...
22:06:19.205 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:217] Notification listener added!
22:06:19.205 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:116] Adding packet listener...
22:06:19.205 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:118] Packet listener added!
22:06:19.310 DEBUG 12616 sr_packets\src\from_game\borderline.rs:8 sr_packet::from_game::borderline::BorderlineMessage_LoginAccountRMC sending msg: 0xa:14 LoginAccountRMC { data: LoginAccountData { email: "***", password: "****" } }
22:06:19.710 DEBUG 12616 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: 19039, token: 9343401028495996278, character: Some(CNetWorldPlayerCompressedVO { guid: 24872, gold: 24842, name: "RapujacyBigos", portrait: 304, border: 5, texture: 1, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1161339, elo: 0, highest_elo: 26344 }), bfp: 11477, relics: 0 }) } }
22:06:19.759 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetPlayerServerSettings: []
22:06:19.759 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetPlayerServerSettingsRMC sending msg: 0x1:2e GetPlayerServerSettingsRMC { request: 8 }
22:06:19.971 INFO 5800 ???:??? 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}
22:06:19.971 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetServerTunables: []
22:06:19.971 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetServerTunablesRMC sending msg: 0x1:45 GetServerTunablesRMC { request: 9 }
22:06:20.196 INFO 5800 ???:??? UI (Lua) [=?] Received response Network_GetServerTunables
22:06:20.464 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetForgeThemes: []
22:06:20.464 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetForgeThemesRMC sending msg: 0x1:51 GetForgeThemesRMC { request: 10 }
22:06:20.540 INFO 5800 ???:??? 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}]}
22:06:20.566 INFO 5800 src\patches\changes\forge_map.rs:22 proxy::patches::changes::forge_map forge map selected
22:06:20.570 DEBUG 12616 from_server\src\borderline.rs:70 from_server::borderline::BorderlineMessage_LoginCharacterRMR received msg: 0xa:19 LoginCharacterRMR
22:06:20.582 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllLimitedPoolsForCharacterRMC sending msg: 0x7:f GetAllLimitedPoolsForCharacterRMC
22:06:20.666 DEBUG 12616 from_server\src\pregame.rs:65 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 }] }
22:06:20.678 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllCardsForCharacterCompressedRMC sending msg: 0x7:26 GetAllCardsForCharacterCompressedRMC
22:06:20.883 DEBUG 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetAllCardsForCharacterCompressedRMR received msg: 0x7:4e GetAllCardsForCharacterCompressedRMR { cardpools: [CardPool { cards_size: 524, pool: 1, limited_pool_id: 0 }, CardPool { cards_size: 0, pool: 3, limited_pool_id: 1 }] }
22:06:20.883 INFO 12616 from_server\src\pregame.rs:773 from_server::pregame::internal Pool: 1, Limited pool id: 0
22:06:20.891 INFO 12616 from_server\src\pregame.rs:773 from_server::pregame::internal Pool: 2, Limited pool id: 0
22:06:20.891 INFO 12616 from_server\src\pregame.rs:773 from_server::pregame::internal Pool: 3, Limited pool id: 1
22:06:20.903 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllCardUpgradesForCharacterCompressedRMC sending msg: 0x7:24 GetAllCardUpgradesForCharacterCompressedRMC
22:06:21.014 DEBUG 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetAllCardUpgradesForCharacterCompressedRMR received msg: 0x7:4c GetAllCardUpgradesForCharacterCompressedRMR { upgrades: Upgrades { upgrades_size: 970 } }
22:06:21.083 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllBoostersForCharacterRMC sending msg: 0x7:21 GetAllBoostersForCharacterRMC
22:06:21.191 INFO 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetAllBoostersForCharacterRMR received msg: 0x7:49 GetAllBoostersForCharacterRMR
22:06:21.199 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetAllDecksForCharacterRMC sending msg: 0x7:1c GetAllDecksForCharacterRMC
22:06:21.452 DEBUG 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetAllDecksForCharacterRMR received msg: 0x7:43 GetAllDecksForCharacterRMR { decks: Decks { decks_size: 77 } }
22:06:21.794 INFO 5800 ???:??? PG (PREGAME) The mapfile .\bf1\map\PVP\12601_PvP_02p_Skjal2.map doesn't exist!
22:06:21.815 INFO 5800 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!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_1vs1 doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_1vs1 doesn't exist!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_2vs2 doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_2vs2 doesn't exist!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_1P doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_1P doesn't exist!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_2P doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_2P doesn't exist!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_4P doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_4P doesn't exist!
22:06:21.822 INFO 5800 ???:??? PG (PREGAME) The mapfile UGM_DummyMap_3vs3 doesn't exist!
22:06:21.822 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVruntime_error@std@@: The mapfile UGM_DummyMap_3vs3 doesn't exist!
22:06:23.992 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetFinishedMapsForCharacterRMC sending msg: 0x8:42 GetFinishedMapsForCharacterRMC
22:06:24.041 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetFinishedMapsForCharacterRMR received msg: 0x8:58 GetFinishedMapsForCharacterRMR { maps: [FinishedMap { id: 100, difficulty: 3 }, FinishedMap { id: 99, difficulty: 3 }, FinishedMap { id: 98, difficulty: 3 }, FinishedMap { id: 37, difficulty: 3 }, FinishedMap { id: 84, difficulty: 3 }, FinishedMap { id: 74, difficulty: 3 }, FinishedMap { id: 10, difficulty: 3 }, FinishedMap { id: 57, difficulty: 3 }, FinishedMap { id: 60, difficulty: 3 }, FinishedMap { id: 35, difficulty: 3 }, FinishedMap { id: 29, difficulty: 3 }, FinishedMap { id: 26, difficulty: 3 }, FinishedMap { id: 25, difficulty: 3 }, FinishedMap { id: 24, difficulty: 3 }, FinishedMap { id: 45, difficulty: 3 }, FinishedMap { id: 22, difficulty: 3 }, FinishedMap { id: 44, difficulty: 3 }, FinishedMap { id: 21, difficulty: 3 }, FinishedMap { id: 9, difficulty: 3 }, FinishedMap { id: 56, difficulty: 3 }, FinishedMap { id: 8, difficulty: 3 }, FinishedMap { id: 88, difficulty: 3 }, FinishedMap { id: 19, difficulty: 3 }, FinishedMap { id: 20, difficulty: 3 }, FinishedMap { id: 32, difficulty: 3 }, FinishedMap { id: 18, difficulty: 3 }, FinishedMap { id: 67, difficulty: 1 }] }
22:06:24.075 DEBUG 12616 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_GetFriendListRMC sending msg: 0x4:7b GetFriendListRMC
22:06:24.163 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GetFriendListRMR received msg: 0x4:4e GetFriendListRMR { friends: WorldPlayerCompressedListVO { player_count: 12 } }
22:06:24.175 DEBUG 12616 sr_packets\src\from_game\world.rs:8 sr_packet::from_game::world::WorldMessage_GetIgnoreListRMC sending msg: 0x4:78 GetIgnoreListRMC
22:06:24.236 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GetIgnoreListRMR received msg: 0x4:4b GetIgnoreListRMR { ignores: WorldPlayerCompressedListVO { player_count: 2 } }
22:06:24.283 DEBUG 12616 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 }
22:06:24.356 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 3, players: [] }
22:06:24.363 DEBUG 12616 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 }
22:06:24.439 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 4, players: [] }
22:06:24.445 DEBUG 12616 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 }
22:06:24.504 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 5, players: [] }
22:06:24.515 DEBUG 12616 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 }
22:06:24.559 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 6, players: [] }
22:06:24.562 DEBUG 12616 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 }
22:06:24.634 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 1073, players: [CNetWorldPlayerCompressedVO { guid: 24872, gold: 0, name: "RapujacyBigos", portrait: 304, border: 5, texture: 1, title: 0, is_gm: false, state: 0, location: 0, map: Introduction, experience: 1161339, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 53535, gold: 0, name: "Bozzah", portrait: 1128, border: 26, texture: 1, title: 60, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 3672856, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 34413, gold: 0, name: "ComputerFox", portrait: 5036, border: 0, texture: 0, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1671, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 69931, gold: 0, name: "MagicMaic", portrait: 524, border: 14, texture: 17, title: 10, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 563530, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 42591, gold: 0, name: "CtFat", portrait: 5056, border: 26, texture: 2, title: 1, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 12974344, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 14468, gold: 0, name: "Irysunna", portrait: 5064, border: 26, texture: 0, title: 36, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1629807, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 1181, gold: 0, name: "LexTalionis", portrait: 5018, border: 0, texture: 3, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 751330, elo: 0, highest_elo: 0 }] }
22:06:24.644 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:06:24.644 DEBUG 12616 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 }
22:06:25.020 TRACE 5800 src\patches\pvp_match.rs:212 proxy::patches::pvp_match './bf1/map/sandbox\19000_Sandbox01\config\map_settings.json': Not found
22:06:25.020 TRACE 5800 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 })
22:06:25.020 WARN 5800 ???:??? gd log "[%i] loading map: %s"
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionLog
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
22:06:25.051 TRACE 5800 src\patches\lua_functions\gds.rs:773 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
22:06:25.091 INFO 5800 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
22:06:25.094 INFO 5800 ???:??? Pathing.Manager CPathManagerConstructed
22:06:25.766 DEBUG 5800 src\patches\pvp_match.rs:301 proxy::patches::pvp_match default? true
22:06:25.766 DEBUG 5800 src\patches\pvp_match.rs:309 proxy::patches::pvp_match timer shown? true
22:06:25.766 DEBUG 5800 src\patches\pvp_match.rs:317 proxy::patches::pvp_match score shown? true
22:06:25.766 DEBUG 5800 src\patches\pvp_match.rs:342 proxy::patches::pvp_match match extending disable true
22:06:25.858 DEBUG 5800 src\game_config\get_set.rs:24 proxy::game_config::get_set debugmainmenu:stickyinfo = 
22:06:25.860 INFO 5800 ???:??? VisManager Loaded 4 different playercolors.
22:06:25.862 INFO 5800 ???:??? VisManager Loaded 5 Flame Locator mappings
22:06:25.862 INFO 5800 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
22:06:25.889 INFO 5800 ???:??? CVisSoundWorldPlugin Loaded 3 Reverb settings, defaults to bf1/sound/ir_presets/default.ifr with dry level 0.030000
22:06:25.937 INFO 5800 ???:??? VisMain Starting Build Scene...
22:06:32.178 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 1
22:06:32.178 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:32.591 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:383] Online Mode initialize!
22:06:32.591 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:158] Initialize Persistent Views
22:06:32.593 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:06:32.593 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:06:32.593 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:74] show playerlist
22:06:32.593 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:06:32.631 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:12] AddDelegates...PlayerListController...
22:06:32.785 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:22] Initializing inventory controller.
22:06:32.785 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:49] Adding inventory controller delegates.
22:06:34.337 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:379] Toggling show inventory: 0
22:06:34.338 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:387] Switching inventory tabs.
22:06:34.340 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:153] Updating mode selection.
22:06:34.340 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:249] Attempting to select cardpool by dropdown item index: -1
22:06:34.341 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:176] Cardpool found with id:1
22:06:34.341 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:176] Cardpool found with id:2
22:06:34.341 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:176] Cardpool found with id:3
22:06:34.341 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:249] Attempting to select cardpool by dropdown item index: 0
22:06:34.344 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:232] Updating inventory filter categories.
22:06:34.344 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:282] Resetting inventory filters.
22:06:34.352 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 0
22:06:34.353 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:34.353 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 7
22:06:34.353 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:34.353 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 6
22:06:34.353 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:34.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:13] Initializing deck bar controller.
22:06:34.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:22] Initializing deck bar delegates.
22:06:34.404 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:17] EventWindowController...Initialize
22:06:34.404 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:19] ...done
22:06:34.404 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:30] EventWindowController:AddDelegates()
22:06:34.427 INFO 5800 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:13] Created new task RefreshPlayerRegionList
22:06:34.427 INFO 5800 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:46] Initialize...PlayerLocationSearchController...
22:06:34.427 INFO 5800 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:126] InitializeScreen...PlayerLocationSearchController...
22:06:34.523 INFO 5800 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:62] PlayerLocationSearchController...AddDelegates...
22:06:34.661 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:222] Checking for new mail ... checkServer=1
22:06:34.663 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetInGameMailRMC sending msg: 0x7:14 GetInGameMailRMC
22:06:34.714 INFO 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetInGameMailRMR received msg: 0x7:3c GetInGameMailRMR
22:06:34.778 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:202] NavigationController.ActivateNewMailFX
22:06:34.778 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
22:06:34.778 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:18] AddDelegates...NavigationController
22:06:34.779 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:386] After Persistent Views initializing!
22:06:34.779 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to News
22:06:34.779 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'Login'
22:06:34.780 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'News'
22:06:34.780 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:83] Creating view bf1/ui/dialogs/pregame/news/pg_news_view.xml
22:06:35.125 INFO 5800 ???:??? UI (Lua) [=?]: [pg_leaderboard_controller.lub:23] LeaderboardController:__init()
22:06:35.126 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:06:35.126 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:06:35.126 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:06:35.127 DEBUG 12616 sr_packets\src\from_game\pregame.rs:14 sr_packet::from_game::pregame::PreGameMessage_GetCharacterStatisticRMC sending msg: 0x7:1e GetCharacterStatisticRMC
22:06:35.188 DEBUG 12616 from_server\src\pregame.rs:65 from_server::pregame::PreGameMessage_GetCharacterStatisticRMR received msg: 0x7:46 GetCharacterStatisticRMR { wins: [49, 57, 41, 27, 27, 14, 15, 30, 333, 2, 1, 3, 7, 8, 37, 6, 13, 26, 4, 2, 46, 0, 0, 9], stats: [0, 0, 0, 0, 0, 0, 0, 0, 4, 0], cards: 1091, elo: 0, time_in_pve_matches: 823064, time_in_pvp_matches: 1445 }
22:06:35.196 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetQuestsAndAchievements: []
22:06:35.196 INFO 5800 ???:??? UI (Lua) [=?]: [pg_news_controller.lub:283] NewsController:SwitchTab(1)
22:06:35.196 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetQuestsAndAchievementsRMC sending msg: 0x1:f GetQuestsAndAchievementsRMC { request: 11 }
22:06:35.196 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetIngameEventNews: {"Language":"en"}
22:06:35.197 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetIngameEventNewsRMC sending msg: 0x1:33 GetIngameEventNewsRMC { request: 12, language: "en" }
22:06:35.197 INFO 5800 ???:??? UI (Lua) [=?]: [pg_news_controller.lub:170] NewsController:Initialize() 
22:06:35.197 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to News
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_inventory_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_playerlist_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_navigation_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_deck_bar_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_request_list_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_public_chatwindow_wnd State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_ls_playerlist_view State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:110] Hiding persistent View: pg_private_chatwindow_wnd State: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 1
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:124] Deckbuilder is ParentView
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:132] ShowPregameSections
22:06:35.198 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
22:06:35.208 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:06:35.208 DEBUG 12616 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 }
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:321] Deckbuilder Loaded!
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:654] DeckBarController:OnPregame
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:256] Attempt at toggling deckbar status. New status 1
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:272] Did not toggle deckbar active status
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:664] Inializing deck slots.
22:06:35.209 INFO 5800 ???:??? UI (Lua) [=?]: [pg_inventory_controller.lub:199] Current deck changed.
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:278] Deck changed to 0000ConvSOLO
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:602] Toggled deck edit mode. New status 0
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:287] Updating deck bar with deck 0000ConvSOLO
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:169] Updating lock deck button
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:215] Updating deck name
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:297] Resetting all card slots
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:223] Updating card slots
22:06:35.210 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:202] Updating cover card
22:06:35.224 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:183] Updating new deck button
22:06:35.280 WARN 12616 src\networking\connect.rs:850 proxy::networking::connect LOGIN: 16.1463582s
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:128] Set ExitBtnState 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.386 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:160] Change contacts button icon
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:152] We are in the sandbox and need to change PushButtonStates
22:06:35.387 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:156] Set SectionsPushButton state 1
22:06:35.388 INFO 5800 ???:??? UI (Lua) [=?] Received response Network_GetQuestsAndAchievements
22:06:35.495 INFO 5800 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
22:06:38.317 INFO 5800 ???:??? UI (Lua) [=?] Received response Network_GetIngameEventNews
22:06:38.436 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_MapOfTheDayNotification: {"DateTimestamp":1749722400,"DoneToday":false,"Map":56}
22:06:38.436 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_MapInfectionNotification: {"DoneThisWeek":false,"Infections":[{"DateTimestamp":1749463200,"Element":3,"Map":20,"ModifiersPerDifficulty":{"1":[2031010,2031048],"2":[2031010,2031048],"3":[2031010,2031048,2031041]}},{"DateTimestamp":1749463200,"Element":2,"Map":101,"ModifiersPerDifficulty":{"1":[2031000,2031044],"2":[2031000,2031044],"3":[2031000,2031044,2031050]}},{"DateTimestamp":1749463200,"Element":2,"Map":102,"ModifiersPerDifficulty":{"1":[2031000,2031044],"2":[2031000,2031044],"3":[2031000,2031044,2031050]}},{"DateTimestamp":1749463200,"Element":2,"Map":103,"ModifiersPerDifficulty":{"1":[2031000,2031044],"2":[2031000,2031044],"3":[2031000,2031044,2031050]}}]}
22:06:38.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonNotification: {"Season":{"EndTimestamp":1759312800,"Guid":1,"StartTimestamp":1748772000}}
22:06:38.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonChallengeNotification: {"Challenges":[],"HasCollectables":false,"Season":1}
22:06:38.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
22:06:38.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification Network_UpdateSeasonProgressNotification: {"HasCollectables":false,"Milestones":[],"Points":0.0,"Season":1}
22:06:38.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:212] NavigationController.ActivateSeasonFX = 0
22:06:38.555 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVCFsException@@: Stream exception: Error reading XML. File: C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml Error-Code: 0 Error-Text:  Row: 1 Column: 1 
22:06:38.569 WARN 5800 ???:??? UI CGUILoadScreenAudioStore::Save: Error saving C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml / Reason: Stream exception: Error reading XML. File: C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml Error-Code: 0 Error-Text:  Row: 1 Column: 1 
22:06:39.414 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
22:06:39.414 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'News'
22:06:39.414 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'GameWizard'
22:06:39.414 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:48] Initialize GameSection!
22:06:39.416 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml)
22:06:39.416 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
22:06:39.416 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #0
22:06:39.416 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:83] Creating view bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
22:06:39.935 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 3, state: 1, location: 3, player: 37073, player_name: "Knochi92", message: "wtb booster 410" }
22:06:40.163 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 16
22:06:40.163 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:11] WorldmapController...Initialize
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:13] ...done
22:06:40.164 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:117] WorldmapController:AddDelegates()
22:06:40.168 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:227] WorldmapController:InitializeHappyHourIndicator()
22:06:40.168 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:236] WorldmapController:UpdateHappyHourIndicator(true)
22:06:40.169 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:255] Hide worldmap happy hour indicator.
22:06:40.190 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:265] WorldmapController...Update...
22:06:40.200 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
22:06:40.268 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:40.272 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
22:06:40.321 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:40.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:06:40.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:06:40.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:06:40.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:06:40.437 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:11] WorldmapController...Initialize
22:06:40.445 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:265] WorldmapController...Update...
22:06:40.448 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
22:06:40.503 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:40.507 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
22:06:40.552 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:40.645 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:06:40.665 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:06:40.665 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:06:40.665 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:06:40.665 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:06:48.886 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:351] WorldmapController...Refresh...
22:06:48.886 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:265] WorldmapController...Update...
22:06:48.894 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
22:06:48.943 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:48.953 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
22:06:48.996 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:06:56.580 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to Forge
22:06:56.580 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'GameWizard'
22:06:56.581 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:06:56.581 DEBUG 12616 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 }
22:06:56.587 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'Forge'
22:06:56.587 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:391] Activate Deckbuiler
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 0
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:124] Deckbuilder is ParentView
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:136] HidePregameSections
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 1
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:06:56.590 INFO 5800 ???:??? UI (Lua) [=?]: [pg_packet_listener.lub:97] Sending request Network_GetComposedPvPDetails: []
22:06:56.590 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_GetComposedPvPDetailsRMC sending msg: 0x1:21 GetComposedPvPDetailsRMC { request: 13 }
22:06:56.640 INFO 5800 ???:??? UI (Lua) [=?] Received response Network_GetComposedPvPDetails: {"CurrentDecks":[],"ErrorCode":1.0,"SecondsLeft":0.0}
22:06:58.999 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:351] WorldmapController...Refresh...
22:07:09.001 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:351] WorldmapController...Refresh...
22:07:18.583 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 1073, state: 1, location: 4, player: 30447, player_name: "YellowJAcket", message: "RPVE 6++ need 3" }
22:07:19.004 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:351] WorldmapController...Refresh...
22:07:22.919 INFO 5800 ???:??? UI (Lua) [=?]: [pg_notification_listener.lub:167] Received notification ForgeSpeedChanged: {"Maximum":32.0,"Minimum":0.25,"Paused":false,"Speed":1.0}
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'Forge'
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:400] DeActivate Deckbuiler
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 1
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:124] Deckbuilder is ParentView
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:132] ShowPregameSections
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:259] SectionBrowser.SwitchSection: Opening section 'GameWizard'
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:07:23.990 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:11] WorldmapController...Initialize
22:07:23.999 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:265] WorldmapController...Update...
22:07:24.005 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: true, map: NotAMap }
22:07:24.068 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:07:24.076 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: NotAMap }
22:07:24.117 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:07:24.241 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:07:24.251 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:24.251 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:24.252 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:24.252 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:25.728 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:541] WorldmapController:OnSelectMap(12200)
22:07:25.736 DEBUG 12616 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_LeaveChatChannelRMC sending msg: 0x6:14 LeaveChatChannelRMC { channel: 1073 }
22:07:25.783 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_LeaveChatChannelRMR received msg: 0x6:1a LeaveChatChannelRMR
22:07:25.806 DEBUG 12616 sr_packets\src\from_game\chat.rs:7 sr_packet::from_game::chat::ChatMessage_JoinChatRegionRMC sending msg: 0x6:15 JoinChatRegionRMC { channel_type: 1, map: Convoy }
22:07:25.875 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionRMR received msg: 0x6:1b JoinChatRegionRMR { channel: 897, players: [CNetWorldPlayerCompressedVO { guid: 24872, gold: 0, name: "RapujacyBigos", portrait: 304, border: 5, texture: 1, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1161339, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 118717, gold: 0, name: "vshocK", portrait: 1477, border: 2, texture: 1, title: 59, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1698331, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 52253, gold: 0, name: "Xanderlord", portrait: 5005, border: 2, texture: 1, title: 16, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 10685523, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 42807, gold: 0, name: "Souky", portrait: 1195, border: 14, texture: 0, title: 62, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 190267, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 59201, gold: 0, name: "Bananahobbit", portrait: 5056, border: 2, texture: 0, title: 9, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 4779493, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 68364, gold: 0, name: "GetmySwagooo", portrait: 1578, border: 34, texture: 3, title: 64, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 2607703, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 18018, gold: 0, name: "TREX", portrait: 5031, border: 11, texture: 4, title: 21, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 11419167, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 123315, gold: 0, name: "bobby1104", portrait: 5058, border: 2, texture: 1, title: 10, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 641166, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 111132, gold: 0, name: "AxxE", portrait: 1128, border: 13, texture: 1, title: 9, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 416913, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 45189, gold: 0, name: "GreenRays", portrait: 5075, border: 2, texture: 16, title: 14, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 3702609, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 37073, gold: 0, name: "Knochi92", portrait: 1465, border: 14, texture: 0, title: 9, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 324453, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 37516, gold: 0, name: "Napstar93", portrait: 1124, border: 19, texture: 0, title: 59, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 376874, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 60298, gold: 0, name: "condorzo", portrait: 1588, border: 8, texture: 3, title: 13, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 6596605, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 45469, gold: 0, name: "yOstenEric", portrait: 368, border: 13, texture: 16, title: 59, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 768613, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 8521, gold: 0, name: "Yuah", portrait: 1276, border: 32, texture: 11, title: 58, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1737192, elo: 27212, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 94527, gold: 0, name: "B1GC1LCKEN3ERGY", portrait: 979, border: 0, texture: 0, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 50748, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 13172, gold: 0, name: "Azino", portrait: 683, border: 16, texture: 3, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 841574, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 41253, gold: 0, name: "Vrizz", portrait: 5085, border: 11, texture: 4, title: 19, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 9430200, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 6820, gold: 0, name: "VegetaSSB", portrait: 1533, border: 14, texture: 3, title: 59, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1601091, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 15340, gold: 0, name: "FoodForThought", portrait: 1021, border: 33, texture: 3, title: 66, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 19951731, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 5355, gold: 0, name: "buttkicker", portrait: 1035, border: 23, texture: 14, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 2119651, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 124407, gold: 0, name: "RagnarRRRR", portrait: 5023, border: 5, texture: 0, title: 0, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 727608, elo: 97281, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 116939, gold: 0, name: "xTHEDAWN", portrait: 1710, border: 33, texture: 19, title: 19, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 11709277, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 58210, gold: 0, name: "Juhi5", portrait: 1704, border: 14, texture: 1, title: 60, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 3163651, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 56875, gold: 0, name: "Matzoe", portrait: 5083, border: 20, texture: 1, title: 12, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 1911317, elo: 0, highest_elo: 0 }, CNetWorldPlayerCompressedVO { guid: 37166, gold: 0, name: "Kleee", portrait: 1688, border: 14, texture: 2, title: 11, is_gm: false, state: 0, location: 0, map: NotAMap, experience: 965571, elo: 0, highest_elo: 0 }] }
22:07:25.890 INFO 5800 ???:??? UI (Lua) [=?]: [pg_player_location_search_controller.lub:150] OnChatRegionChanged...PlayerLocationSearchController...
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:55] ScreenFlow:Next()
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:72] current screen:bf1/ui/dialogs/pregame/worldmap/pg_worldmap_view_new.xml
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_ranked_games_view.xml
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_user_generated_view.xml
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pvp_view.xml
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:78] condition hit!
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml)
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:528] WorldmapController...Close...
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:530] ...RemoveAllDelegates
22:07:25.893 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:178] WorldmapController:RemoveAllDelegates()
22:07:25.894 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
22:07:25.894 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #1
22:07:25.894 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:83] Creating view bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 44
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:15] PvEGameSelectionController...Initialize
22:07:26.094 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:17] ...done.
22:07:26.095 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:23] GameSelectionControllerBase...Initialize...
22:07:26.095 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:354] GameSelectionControllerBase...CheckNextBtnState...
22:07:26.095 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:356] ...leader, hiding next button.
22:07:26.095 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:175] PvEGameSelectionController...UpdateDifficultyButtons...
22:07:26.096 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:192] ...easy.
22:07:26.096 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:195] ...normal.
22:07:26.096 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:198] ...hard.
22:07:26.107 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMC sending msg: 0x8:3f GetAllOpenCustomGamesRMC { pvp: false, map: Convoy }
22:07:26.155 INFO 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_GetAllOpenCustomGamesRMR received msg: 0x8:55 GetAllOpenCustomGamesRMR
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:428] GameSelectionControllerBase... CheckForCorrectJoinBtnState...
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:274] UpdateDeckMode...GameSelectionControllerBase.. - early out, no sufficient widgets!
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:354] GameSelectionControllerBase...CheckNextBtnState...
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:356] ...leader, hiding next button.
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:389] GameSelectionControllerBase...CheckCreateBtnState...
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:405] ...no match maker concrete, enable.
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:428] GameSelectionControllerBase... CheckForCorrectJoinBtnState...
22:07:26.184 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:98] PvEGameSelectionController...AddDelegates...
22:07:26.185 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:51] GameSelectionControllerBase:AddDelegates()
22:07:26.193 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:354] GameSelectionControllerBase...CheckNextBtnState...
22:07:26.193 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:356] ...leader, hiding next button.
22:07:26.193 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:07:26.201 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.201 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.201 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.201 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.211 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.211 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.211 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.211 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.212 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.212 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.213 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.213 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.214 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:26.214 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:26.214 INFO 5800 ???:??? UI (Lua) [=?]: [pg_worldmap_controller_new.lub:178] WorldmapController:RemoveAllDelegates()
22:07:27.239 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_pve_controller.lub:248] PvEGameSelectionController...OnSelectDifficulty...
22:07:28.630 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_LeaveChatRegionChannelNotification received msg: 0x6:f LeaveChatRegionChannelNotification { channel: 897, player: 45469 }
22:07:36.475 INFO 5800 ???:??? UI (Lua) [=?]: [match_utils.lub:60] remove automatch...
22:07:36.475 INFO 5800 ???:??? UI (Lua) [=?]: [match_utils.lub:69] ...no match maker
22:07:36.475 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:303] GameSelectionControllerBase...Close...
22:07:36.475 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:310] GameSelectionControllerBase:RemoveAllDelegates()
22:07:36.487 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_CreateCustomGameRMC sending msg: 0x8:43 CreateCustomGameRMC { deck: 905210, map: Convoy, difficulty: 2, reward_mode: 3, pvp: false, unlimited_deck_mode: false, map_file_checksum: 0, combined_checksum: 0, map_names: [], map_of_the_month: false, is_infected: false, modifiers: [] }
22:07:36.594 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CreateCustomGameRMR received msg: 0x8:59 CreateCustomGameRMR { error: 0, player: 24872, match_id: 35826, pvp: false }
22:07:36.594 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameCreatedNotification received msg: 0x8:2a CustomGameCreatedNotification { player: 24872, map: Convoy, difficulty: 2, match_id: 35826, pvp: false, is_open: true, unlimited_deck_mode: false, map_file_cc: 0, combined_crc: 0, is_motd: false, is_infected: false, modifiers: [] }
22:07:36.594 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 35826, map: Convoy, pvp: false, team1: [Some(MatchPlayer { id: 24872, name: "RapujacyBigos", deck_name: "0000ConvSOLO", cover_card: 362, ready: false, pve_level: 12, elo: 0, deck_level: 101 }), None], team2: [] }
22:07:36.656 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_ChangeCustomGameOpenForOtherPlayersStateRMC sending msg: 0x8:40 ChangeCustomGameOpenForOtherPlayersStateRMC { match_id: 35826, open: true }
22:07:36.708 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_ChangeCustomGameOpenForOtherPlayersStateRMR received msg: 0x8:56 ChangeCustomGameOpenForOtherPlayersStateRMR { error: 0 }
22:07:36.711 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:36.711 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:36.711 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:36.714 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:36.718 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:36.718 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:120] OnMatchCreated...EventWindowController
22:07:36.718 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:55] ScreenFlow:Next()
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:72] current screen:bf1/ui/dialogs/pregame/game_selection/pg_game_selection_pve_view.xml
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:76] checking screenNext:bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:78] condition hit!
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml)
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:303] GameSelectionControllerBase...Close...
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:310] GameSelectionControllerBase:RemoveAllDelegates()
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:11] DoTransition: bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:35] OnNextView! #2
22:07:36.719 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:83] Creating view bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 45
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:10] ShowTutorial 47
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_tutorial_helper.lub:39] Don't show tutorials, or tutorial already visited...
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:32] PregameSection.Open
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:39] PregameSection.Open: Setting position on screen
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:43] PregameSection.Open: Initialize controller
22:07:36.820 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:26] MatchmakingController...Initialize
22:07:36.824 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:07:36.824 DEBUG 12616 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: Convoy }
22:07:36.824 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:29] ...done
22:07:36.824 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:36.843 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:596] MatchmakingController...CreateTeamSlotList...
22:07:36.843 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:604] TeamFound: 4
22:07:36.845 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:608] SlotCount: 2
22:07:36.845 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:611] MatchmakingController...CreateTeamSlotList...CreatePlayerBanner...
22:07:36.958 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:616] Adding teamslot to team1: 1
22:07:36.959 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:611] MatchmakingController...CreateTeamSlotList...CreatePlayerBanner...
22:07:37.000 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:616] Adding teamslot to team1: 2
22:07:37.002 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:604] TeamFound: 7
22:07:37.004 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:185] MatchmakingController:AddDelegates()1
22:07:37.008 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:37.008 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...1
22:07:37.009 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_SetSpeedRunRMR received msg: 0x8:4c SetSpeedRunRMR
22:07:37.016 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section.lub:52] Show ActiveView
22:07:37.046 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:37.046 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:37.046 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:37.048 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:37.052 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:37.052 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
22:07:37.052 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:37.052 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:37.055 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:28] Destroying animated widget
22:07:37.055 INFO 5800 ???:??? UI (Lua) [=?]: [animated_widget.lub:30] Destroyed animated widget
22:07:37.057 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_selection_base_controller.lub:310] GameSelectionControllerBase:RemoveAllDelegates()
22:07:43.229 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionChannelNotification received msg: 0x6:11 JoinChatRegionChannelNotification { channel: 897, player: CNetWorldPlayerCompressedVO { guid: 122581, gold: 0, name: "drentbyspeech", portrait: 5001, border: 0, texture: 0, title: 1, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1420232, elo: 0, highest_elo: 0 } }
22:07:46.507 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatRegionChannelNotification received msg: 0x6:11 JoinChatRegionChannelNotification { channel: 897, player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:47.451 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupListUpdatedNotification received msg: 0x4:1a GroupListUpdatedNotification { group: Group { id: 7603, version: 1, leader: CNetWorldPlayerCompressedVO { guid: 24872, gold: 0, name: "RapujacyBigos", portrait: 304, border: 5, texture: 1, title: 0, is_gm: false, state: 1, location: 4, map: Convoy, experience: 1161339, elo: 0, highest_elo: 0 }, members: [CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 }] } }
22:07:47.451 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 35826, map: Convoy, pvp: false, team1: [Some(MatchPlayer { id: 24872, name: "RapujacyBigos", deck_name: "0000ConvSOLO", cover_card: 362, ready: false, pve_level: 12, elo: 0, deck_level: 101 }), None], team2: [] }
22:07:47.462 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatChannelRMR received msg: 0x6:1c JoinChatChannelRMR
22:07:47.478 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:47.478 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:47.478 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:47.480 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:47.512 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:47.514 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:47.524 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:47.524 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:47.524 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:47.524 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:47.525 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:47.530 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:47.531 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:47.536 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:47.536 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
22:07:47.536 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:47.536 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:47.596 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 4, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:50.929 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 35826, map: Convoy, pvp: false, team1: [Some(MatchPlayer { id: 24872, name: "RapujacyBigos", deck_name: "0000ConvSOLO", cover_card: 362, ready: false, pve_level: 12, elo: 0, deck_level: 101 }), Some(MatchPlayer { id: 103817, name: "revy", deck_name: "WTS", cover_card: 376, ready: false, pve_level: 12, elo: 0, deck_level: 102 })], team2: [] }
22:07:50.933 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:50.933 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:50.933 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:50.935 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:50.942 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:50.942 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:50.947 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:50.947 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
22:07:50.947 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:50.947 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:51.566 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:52.181 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_PlayerReadyStatusChangedRMC sending msg: 0x8:47 PlayerReadyStatusChangedRMC { deck: 905210, match_id: 35826, ready: true }
22:07:52.244 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_PlayerReadyStatusChangedRMR received msg: 0x8:5d PlayerReadyStatusChangedRMR { error: 0 }
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:146] Player toggled ready status. New status 1
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:256] Attempt at toggling deckbar status. New status 0
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:259] Toggled deckbar active status. New status 0
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:598] Did not toggle deck edit mode
22:07:52.245 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:156] Updating decks button. New status 0
22:07:52.249 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:183] Updating new deck button
22:07:52.249 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:169] Updating lock deck button
22:07:52.249 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:909] OnPlayerReadyStateChanged...MatchmakingController...
22:07:52.261 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 35826, map: Convoy, pvp: false, team1: [Some(MatchPlayer { id: 24872, name: "RapujacyBigos", deck_name: "0000ConvSOLO", cover_card: 362, ready: true, pve_level: 12, elo: 0, deck_level: 101 }), Some(MatchPlayer { id: 103817, name: "revy", deck_name: "WTS", cover_card: 376, ready: false, pve_level: 12, elo: 0, deck_level: 102 })], team2: [] }
22:07:52.267 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:52.267 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:52.267 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:52.269 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:52.273 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:52.273 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:52.277 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:52.277 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
22:07:52.277 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:52.277 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:55.789 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 4, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:56.483 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CustomGameUpdatedNotification received msg: 0x8:2c CustomGameUpdatedNotification { match_id: 35826, map: Convoy, pvp: false, team1: [Some(MatchPlayer { id: 24872, name: "RapujacyBigos", deck_name: "0000ConvSOLO", cover_card: 362, ready: true, pve_level: 12, elo: 0, deck_level: 101 }), Some(MatchPlayer { id: 103817, name: "revy", deck_name: "frost", cover_card: 288, ready: true, pve_level: 12, elo: 0, deck_level: 86 })], team2: [] }
22:07:56.491 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:56.491 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:56.491 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:56.493 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:56.498 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:56.498 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:56.503 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:56.503 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:896] OnMatchUpdated...MatchmakingController...
22:07:56.503 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:302] MatchmakingController...UpdateMinimapData...
22:07:56.503 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:172] MatchmakingController...DoInitialChecks...
22:07:57.578 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:57.578 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_LeaveChatRegionChannelNotification received msg: 0x6:f LeaveChatRegionChannelNotification { channel: 897, player: 37516 }
22:07:58.767 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:1019] Starting Match...
22:07:58.772 DEBUG 12616 sr_packets\src\from_game\matchmaking.rs:7 sr_packet::from_game::matchmaking::MatchmakingMessage_StartCustomGameRMC sending msg: 0x8:3d StartCustomGameRMC { match_id: 35826 }
22:07:58.957 INFO 12616 src\networking\connect.rs:694 proxy::networking::connect Connecting to match server ...
22:07:58.958 TRACE 12616 src\networking\connect.rs:483 proxy::networking::connect connecting to: 162.55.91.56:7404 (162.55.91.56:7404)
22:07:58.958 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_StartCustomGameRMR received msg: 0x8:53 StartCustomGameRMR { error: 0 }
22:07:59.064 INFO 12616 src\networking\connect.rs:531 proxy::networking::connect connected TlsStream { io: PollEvented { io: Some(TcpStream { addr: 192.168.74.144:51686, peer: 162.55.91.56:7404, socket: 7896 }) }, session: ClientConnection, state: Stream }
22:07:59.064 INFO 12616 src\networking\connect.rs:715 proxy::networking::connect ... successfully connected ot match server!
22:07:59.064 DEBUG 12616 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RegisterCharacterForGame sending msg: 0x5:8 RegisterCharacterForGame { session: 19039, token: 9343401028495996278 }
22:07:59.064 DEBUG 12616 from_server\src\matchmaking.rs:16 from_server::matchmaking::MatchmakingMessage_CharacterForMatchCompleteNotification received msg: 0x8:c CharacterForMatchCompleteNotification { match_id: 35826, map: Convoy, game_server_ip: 945502114, game_server_port: 7404, team: Team1, seed: 2463, team_id: 0, team_elo_rating: 0, players: [Player { id: 24872, name: "RapujacyBigos", team: Team1, color: 1, deck: Deck { id: 905210, name: "0000ConvSOLO", pool: 1, limited_pool_id: 0, cards: [DeckCard { id: 899887, position: 0, card: Card { id: 899887, card: "Windweavers", upgrades: 3, charges: 3 } }, DeckCard { id: 10242548, position: 1, card: Card { id: 10242548, card: "Shaman", upgrades: 3, charges: 3 } }, DeckCard { id: 13360763, position: 2, card: Card { id: 13360763, card: "Dryad", upgrades: 2, charges: 2 } }, DeckCard { id: 11275731, position: 3, card: Card { id: 11275731, card: "Ensnaring Roots", upgrades: 3, charges: 3 } }, DeckCard { id: 13353660, position: 4, card: Card { id: 13353660, card: "Stranglehold", upgrades: 1, charges: 1 } }, DeckCard { id: 899885, position: 5, card: Card { id: 899885, card: "Surge of Light", upgrades: 3, charges: 3 } }, DeckCard { id: 16590685, position: 6, card: Card { id: 16590685, card: "Parasite Swarm", upgrades: 1, charges: 0 } }, DeckCard { id: 899923, position: 7, card: Card { id: 899923, card: "Breeding Grounds", upgrades: 3, charges: 3 } }, DeckCard { id: 10242543, position: 8, card: Card { id: 10242543, card: "Root Nexus", upgrades: 3, charges: 3 } }, DeckCard { id: 12093551, position: 9, card: Card { id: 12093551, card: "Living Tower", upgrades: 3, charges: 2 } }, DeckCard { id: 7031968, position: 10, card: Card { id: 7031968, card: "Creeping Paralysis", upgrades: 3, charges: 3 } }, DeckCard { id: 11412245, position: 11, card: Card { id: 11412245, card: "Thornbark", upgrades: 3, charges: 3 } }, DeckCard { id: 19461416, position: 12, card: Card { id: 19461416, card: "Razorleaf!", upgrades: 0, charges: 0 } }, DeckCard { id: 15353913, position: 13, card: Card { id: 15353913, card: "Wheel of Gifts", upgrades: 3, charges: 3 } }, DeckCard { id: 15532131, position: 14, card: Card { id: 15532131, card: "Shrine of Memory", upgrades: 3, charges: 0 } }, DeckCard { id: 11412244, position: 15, card: Card { id: 11412244, card: "Revenge", upgrades: 3, charges: 3 } }, DeckCard { id: 899921, position: 16, card: Card { id: 899921, card: "Giant Wyrm", upgrades: 3, charges: 3 } }, DeckCard { id: 1339333, position: 17, card: Card { id: 1339333, card: "Skycatcher", upgrades: 1, charges: 1 } }, DeckCard { id: 11434786, position: 18, card: Card { id: 11434786, card: "Colossus", upgrades: 3, charges: 3 } }, DeckCard { id: 899924, position: 19, card: Card { id: 899924, card: "Regrowth", upgrades: 3, charges: 3 } }], cover_card: Card { id: 899887, card: "Windweavers", upgrades: 3, charges: 3 }, level: 101 }, character: CNetWorldPlayerCompressedVO { guid: 24872, gold: 0, name: "RapujacyBigos", portrait: 304, border: 5, texture: 1, title: 0, is_gm: false, state: 1, location: 4, map: Convoy, experience: 1161339, elo: 0, highest_elo: 0 } }, Player { id: 103817, name: "revy", team: Team1, color: 1, deck: Deck { id: 508903, name: "frost", pool: 1, limited_pool_id: 0, cards: [DeckCard { id: 15850850, position: 0, card: Card { id: 15850850, card: "Master Archers", upgrades: 3, charges: 3 } }, DeckCard { id: 15977636, position: 1, card: Card { id: 15977636, card: "Frost Mage", upgrades: 3, charges: 3 } }, DeckCard { id: 20775276, position: 2, card: Card { id: 20775276, card: "Defenders", upgrades: 3, charges: 3 } }, DeckCard { id: 15919411, position: 3, card: Card { id: 15919411, card: "Phalanx", upgrades: 3, charges: 3 } }, DeckCard { id: 16150321, position: 4, card: Card { id: 16150321, card: "War Eagle", upgrades: 3, charges: 1 } }, DeckCard { id: 14666016, position: 5, card: Card { id: 14666016, card: "Skyelf Templar", upgrades: 2, charges: 1 } }, DeckCard { id: 15850852, position: 6, card: Card { id: 15850852, card: "Silverwind Lancers", upgrades: 3, charges: 3 } }, DeckCard { id: 16797920, position: 7, card: Card { id: 16797920, card: "Avatar of Frost", upgrades: 0, charges: 0 } }, DeckCard { id: 15864832, position: 8, card: Card { id: 15864832, card: "Northland Drake", upgrades: 3, charges: 3 } }, DeckCard { id: 16118018, position: 9, card: Card { id: 16118018, card: "Northland Drake", upgrades: 3, charges: 3 } }, DeckCard { id: 16833117, position: 10, card: Card { id: 16833117, card: "Dreadnought", upgrades: 0, charges: 0 } }, DeckCard { id: 26927977, position: 11, card: Card { id: 26927977, card: "Ironclad", upgrades: 0, charges: 0 } }, DeckCard { id: 15850854, position: 12, card: Card { id: 15850854, card: "Cannon Tower", upgrades: 3, charges: 3 } }, DeckCard { id: 16309480, position: 13, card: Card { id: 16309480, card: "Worldbreaker Gun", upgrades: 2, charges: 0 } }, DeckCard { id: 16825215, position: 14, card: Card { id: 16825215, card: "Glyph of Frost", upgrades: 3, charges: 3 } }, DeckCard { id: 16126657, position: 15, card: Card { id: 16126657, card: "Coldsnap", upgrades: 3, charges: 2 } }, DeckCard { id: 16816429, position: 16, card: Card { id: 16816429, card: "Area Ice Shield", upgrades: 2, charges: 1 } }, DeckCard { id: 16049657, position: 17, card: Card { id: 16049657, card: "Shatter Ice", upgrades: 3, charges: 3 } }, DeckCard { id: 16309481, position: 18, card: Card { id: 16309481, card: "Coat of Protection", upgrades: 3, charges: 3 } }, DeckCard { id: 16743324, position: 19, card: Card { id: 16743324, card: "Maelstrom", upgrades: 3, charges: 0 } }], cover_card: Card { id: 15850850, card: "Master Archers", upgrades: 3, charges: 3 }, level: 86 }, character: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }] }
22:07:59.071 INFO 5800 ???:??? PG (PREGAME) OnCharacterForMatchComplete triggered MatchID: 35826
22:07:59.071 INFO 5800 ???:??? NETWORK ResetSequences triggered old MatchID: 0  new MatchID: 35826
22:07:59.080 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatChannelRMR received msg: 0x6:1c JoinChatChannelRMR
22:07:59.104 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_JoinChatChannelRMR received msg: 0x6:1c JoinChatChannelRMR
22:07:59.105 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 4, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:114] OnMatchStarted...GameSection...
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:127] ... switch to matchmaking on match!
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:101] Can't adopt GroupleaderMapInfo. I'm the groupleader!
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:224] SectionBrowser:SwitchSection to GameWizard
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:53] ScreenFlow:Next(bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml)
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_screen_flow.lub:59] Screen is already active, do nothing: bf1/ui/dialogs/pregame/matchmaking/pg_matchmaking_view.xml
22:07:59.116 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:133] OnStartTimer...GameSection...
22:07:59.128 INFO 5800 ???:??? UI (Lua) [=?]: [timed_task_manager.lub:18] Created new anonymous task
22:07:59.128 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:07:59.128 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:07:59.128 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:07:59.130 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:59.134 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:59.134 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:07:59.138 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:07:59.138 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:167] UpdateButton...State: Hide
22:07:59.138 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:169] ...Hide.
22:07:59.141 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:07:59.602 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:08:00.599 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:08:01.595 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:08:02.099 INFO 5800 ???:??? UI (Lua) [=?]: [timed_task.lub:14] Anonymous timed task was destroyed.
22:08:02.594 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:08:03.592 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:168] OnUpdateTimer...GameSection...
22:08:03.592 INFO 5800 ???:??? UI (Lua) [=?]: [pg_game_section.lub:171] ...loading match!
22:08:03.592 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:245] SectionBrowser.CloseSectionBeforeSwitch: Closing section 'GameWizard'
22:08:03.592 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:08:03.592 DEBUG 12616 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: Convoy }
22:08:03.599 INFO 5800 ???:??? UI (Lua) [=?]: [pg_matchmaking_controller.lub:369] OnSectionClose...MatchMakingController...
22:08:03.599 INFO 5800 ???:??? UI (Lua) [=?]: [pg_eventwindow_controller.lub:146] OnMatchMakingSection...0
22:08:03.599 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
22:08:03.599 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:593] DeckBar isn't active, cant change Edit Mode in inactive state
22:08:03.611 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 2, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:08:03.648 INFO 5800 ???:??? Pathing Destroy Pathing Engine
22:08:03.653 INFO 5800 ???:??? NETWORK PG_StartMatch triggered MatchID: 35826
22:08:03.662 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:08:03.662 DEBUG 12616 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: Convoy }
22:08:03.713 TRACE 5800 src\patches\pvp_match.rs:212 proxy::patches::pvp_match '.\bf1\map\PVE\12200_PvE_02p_Convoy\config\map_settings.json': Not found
22:08:03.713 TRACE 5800 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 })
22:08:03.713 WARN 5800 ???:??? gd log "[%i] loading map: %s"
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionLog
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorSlotRefresh
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionShowUi
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorVoidPowerAddRemove
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionCameraTrackPlay
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorNameTags
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionQuantorCustomNameTags
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptMiniMapAlertPerPlayer
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptModifyTimer
22:08:03.716 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCopyCounter
22:08:03.717 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptActionDamageTeamSquadInRange
22:08:03.717 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptAudioPlay
22:08:03.717 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCardBlockAdd
22:08:03.717 TRACE 5800 src\patches\lua_functions\gds.rs:764 proxy::patches::lua_functions::gds registering RustScriptCardBlockRemove
22:08:03.717 TRACE 5800 src\patches\lua_functions\gds.rs:773 proxy::patches::lua_functions::gds registering RustScriptConditionQuantorEntityKill
22:08:03.740 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 1, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:08:03.857 INFO 5800 ???:??? Pathing Init Pathing Engine: Path finding (Feb 21 2013/13:42:45) '_FINAL, _LIB, '
22:08:03.861 INFO 5800 ???:??? Pathing.Manager CPathManagerConstructed
22:08:06.387 DEBUG 5800 src\patches\pvp_match.rs:301 proxy::patches::pvp_match default? true
22:08:06.387 DEBUG 5800 src\patches\pvp_match.rs:309 proxy::patches::pvp_match timer shown? true
22:08:06.387 DEBUG 5800 src\patches\pvp_match.rs:317 proxy::patches::pvp_match score shown? true
22:08:06.387 DEBUG 5800 src\patches\pvp_match.rs:342 proxy::patches::pvp_match match extending disable true
22:08:06.433 TRACE 5800 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 0, last_rng: 2, barrier_modules: 0, barrier_sets: 0, buildings: B5C41829, figures: 8F7A2F42, objects: 37A425FF, players: 0, power_slots: B56071E7, projectiles: 0, map_scripts: 0, squads: 9A639256, token_slots: 703C76A1, turrets: 99F384B7, world: 74B27034, config_crcs: [F0CE549B, 41C006B8, 63A52C01, B6CE0D8B, B6B33C05] }
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) --- Logging map CRCs ---
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uBarrierModuleCRC: 0
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uBarrierSetCRC: 0
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uBuildingCRC: b5c41829
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uFigureCRC: 8f7a2f42
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uObjectCRC: 37a425ff
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uPlayerCRC: 0
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uPowerSlotCRC: b56071e7
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uProjectileCRC: 0
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uScriptingCRC: 0
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uSquadCRC: 9a639256
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uTokenSlotCRC: 703c76a1
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uTurretCRC: 99f384b7
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) uWorldCRC: 74b27034
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) AssetCRC #1: f0ce549b
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) AssetCRC #2: 41c006b8
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) AssetCRC #3: 63a52c01
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) AssetCRC #4: b6ce0d8b
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) AssetCRC #5: b6b33c05
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) Combined CRC: b7791a72
22:08:06.455 INFO 5800 ???:??? PG (PREGAME) --- End of logging map CRCs ---
22:08:06.921 INFO 5800 ???:??? VisManager Loaded 4 different playercolors.
22:08:06.923 INFO 5800 ???:??? VisManager Loaded 5 Flame Locator mappings
22:08:06.924 INFO 5800 ???:??? VisManager Loaded 3 Generator and 3 Monument descriptions
22:08:06.929 INFO 5800 ???:??? CVisSoundWorldPlugin Loaded 3 Reverb settings, defaults to bf1/sound/ir_presets/default.ifr with dry level 0.030000
22:08:06.968 INFO 5800 ???:??? VisMain Starting Build Scene...
22:08:10.168 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 1, location: 5, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:08:10.176 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_GroupPlayerChangedLocationNotification received msg: 0x4:26 GroupPlayerChangedLocationNotification { player: CNetWorldPlayerCompressedVO { guid: 103817, gold: 0, name: "revy", portrait: 1533, border: 22, texture: 0, title: 0, is_gm: false, state: 2, location: 5, map: Convoy, experience: 1108869, elo: 0, highest_elo: 0 } }
22:08:11.193 WARN 10916 ???:??? Direct3D 11 Create shader resource view : S_OK
22:08:14.070 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 897, state: 1, location: 4, player: 30447, player_name: "YellowJAcket", message: "RPVE 6++ need 1" }
22:08:14.819 ERROR 5800 bf_utils\src\fs\file.rs:56 bf_utils::fs::file Os { code: 2, kind: NotFound, message: "Nie można odnaleźć określonego pliku." } `"effect_proto_bomberman_spawn.xml"`
22:08:14.820 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVCFsException@@: Stream exception: Could not open file for read (effect_proto_bomberman_spawn.xml)
22:08:18.396 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:64] PlayerListController:Update()
22:08:18.396 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:111] ClearList...PlayerListController
22:08:18.396 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:71] show teamlist
22:08:18.398 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:08:18.404 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:08:18.404 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:122] Adding a Character to the Playerlist
22:08:18.409 INFO 5800 ???:??? UI (Lua) [=?]: [pg_playerlist_controller.lub:139] ownteam 4
22:08:18.410 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:648] DeckBarController:OnIngame
22:08:18.410 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:590] Attempting deck edit mode. New status 0
22:08:18.410 INFO 5800 ???:??? UI (Lua) [=?]: [pg_deck_bar_controller.lub:593] DeckBar isn't active, cant change Edit Mode in inactive state
22:08:18.410 INFO 5800 ???:??? UI (Lua) [=?]: [pg_chatwindow_controller.lub:342] ChatWindowController:OnIngame()
22:08:18.410 INFO 5800 ???:??? UI (Lua) [=?]: [pg_chatwindow_controller.lub:342] ChatWindowController:OnIngame()
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:128] Set ExitBtnState 1
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:138] Set SectionsPushButton state 1
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:138] Set SectionsPushButton state 1
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:147] Change contacts button icon
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:133] We are ingame and need to change PushButtonStates
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_navigation_controller.lub:142] Set SectionsPushButton state to 0, they won't be available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:117] Reparenting views, ShowPregamSections: 0
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:127] GameWidget is ParentView
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:136] HidePregameSections
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:141] Hiding persistent Views that are not available ingame
22:08:18.411 INFO 5800 ???:??? UI (Lua) [=?]: [pg_section_browser.lub:432] Match initialized
22:08:29.065 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:08:31.300 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 4, state: 1, location: 4, player: 80502, player_name: "bengel", message: "need 1 good for 8 motm(fire starter)" }
22:08:33.664 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:08:59.066 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:09:02.696 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_LeaveChatRegionChannelNotification received msg: 0x6:f LeaveChatRegionChannelNotification { channel: 897, player: 122581 }
22:09:03.667 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:09:17.206 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 897, state: 1, location: 4, player: 30447, player_name: "YellowJAcket", message: "RPVE 6++ need 1" }
22:09:29.068 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:09:33.668 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:09:59.071 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:10:03.671 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:10:29.073 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:10:30.704 DEBUG 12616 from_server\src\chat.rs:8 from_server::chat::ChatMessage_SayNotification received msg: 0x6:d SayNotification { channel: 897, state: 1, location: 4, player: 30447, player_name: "YellowJAcket", message: "RPVE 6++ need 1" }
22:10:33.671 DEBUG 12616 sr_packets\src\from_game\extensions.rs:55 sr_packet::from_game::extensions::ExtensionCommands_KeepAliveAction sending msg: 0x1:7 KeepAliveAction
22:10:44.288 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:10:44.288 DEBUG 12616 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: Convoy }
22:10:44.300 DEBUG 12616 from_server\src\world.rs:22 from_server::world::WorldMessage_ChangeCharacterLocationStateRMR received msg: 0x4:53 ChangeCharacterLocationStateRMR
22:10:44.300 DEBUG 12616 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: Convoy }
22:10:44.312 DEBUG 12616 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: 57, crc: 3078167154, random_map_crc: 0 }
22:10:44.385 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_GameStartNotification received msg: 0x5:4 GameStartNotification { match_id: 35826 }
22:10:44.386 INFO 5800 ???:??? PG (PREGAME) OnGameStart... game ID = 35826
22:10:44.699 INFO 5800 src\logging\bf_exceptions.rs:56 proxy::logging::bf_exceptions Exception: .?AVCFsException@@: Stream exception: Error reading XML. File: C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml Error-Code: 0 Error-Text:  Row: 1 Column: 1 
22:10:44.699 WARN 5800 ???:??? UI CGUILoadScreenAudioStore::Save: Error saving C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml / Reason: Stream exception: Error reading XML. File: C:\Users\Cegła\Documents\BattleForge\loadingscreensaudiostore\store.xml Error-Code: 0 Error-Text:  Row: 1 Column: 1 
22:10:47.086 DEBUG 12616 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 20, x: 121.79032, y: 123.800026, barrier_to_mount: 0 } }
22:10:47.189 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 29, step: 28, command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 20, x: 121.79032, y: 123.800026, barrier_to_mount: 0 } }
22:10:47.496 TRACE 5800 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 1E, last_rng: 4, simple_ida_crc: 7AB2C0F3 }
22:10:47.497 DEBUG 12616 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: 9359436546278319312, position_hash: 1448512329438806969, power_hash: 5466760487958499599 }, rounded: StateInfoHashes { health_hash: 16877791765718891787, position_hash: 15405325395046083007, power_hash: 6539608651815335371 }, figures: 221, entities: 2464, step: 30 } } } }
22:10:47.502 DEBUG 12616 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: [4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 243, 192, 178, 122, 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, 139, 13, 206, 182, 5, 60, 179, 182] }
22:10:47.912 TRACE 5800 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal '12200_01_1_FreeCrystals_1_P1' for player: 'pl_Player1'
22:10:47.943 TRACE 5800 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal '12200_01_1_FreeCrystals_1_P2' for player: 'pl_Player2'
22:10:47.945 TRACE 5800 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal '12200_01_2_SupportAncientsALL' for player: 'PL_PLAYER1'
22:10:47.946 TRACE 5800 src\patches\pvp_match.rs:357 proxy::patches::pvp_match Adding goal '12200_01_2_SupportAncientsALL' for player: 'PL_PLAYER2'
22:10:48.514 DEBUG 12616 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 119.66181, y: 120.16438, barrier_to_mount: 0 } }
22:10:48.569 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 44, step: 42, command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 119.66181, y: 120.16438, barrier_to_mount: 0 } }
22:10:50.296 DEBUG 12616 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 125.65, y: 117.95, barrier_to_mount: 0 } }
22:10:50.369 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 63, step: 60, command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 125.65, y: 117.95, barrier_to_mount: 0 } }
22:10:50.502 TRACE 5800 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 3C, last_rng: 1, simple_ida_crc: E8CB6F73 }
22:10:53.000 DEBUG 12616 sr_packets\src\from_game\game.rs:8 sr_packet::from_game::game::GameMessage_RequestCommandAction sending msg: 0x5:a RequestCommandAction { command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 125.65, y: 113.75, barrier_to_mount: 0 } }
22:10:53.068 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 91, step: 87, command: ProduceSquad { card: 3000362, player: 2186, card_position: 0, tag: 3000362, played_count: 255, x: 125.65, y: 113.75, barrier_to_mount: 0 } }
22:10:53.496 TRACE 5800 src\patches\debug\desync.rs:172 proxy::patches::debug::desync SyncCRCs { step: 5A, last_rng: 2, simple_ida_crc: 79F2FB4B }
22:10:54.066 ERROR 5800 ???:??? BASE Unhandled exception error. Crash dump written.
22:10:54.066 ERROR 5800 ???:??? BASE 
22:10:58.062 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 116, step: 111, command: ProduceSquad { card: 3000367, player: 2187, card_position: 1, tag: 3065903, played_count: 16, x: 593.41693, y: 128.47804, barrier_to_mount: 0 } }
22:10:58.062 DEBUG 12616 from_server\src\game.rs:8 from_server::game::GameMessage_AnnounceCommandNotification received msg: 0x5:b AnnounceCommandNotification { match_id: 35826, sequence_number: 131, step: 125, command: ProduceSquad { card: 3000367, player: 2187, card_position: 1, tag: 3065903, played_count: 255, x: 596.82043, y: 125.08013, barrier_to_mount: 0 } }