[16:56:16.872]. #REG_STUFF: Launcher = $0, Context = "render.script"
[16:56:16.872]. #DBG: Setting AtPanic handler for lua_State g_render_lua_1
[16:56:17.173]. #DBG: Используется оригинальный обработчик VEH.
[16:56:17.175]. #REG_STUFF: Launcher = $0, Context = "_g.script"
Registering function [$02764F60] base64_decode (string) // convert base64 string to data_buffer object
Registering function [$02764FF0] base64_encode (string|userdata, [size]) // convert string/userdata to base64 string
Registering function [$027657B4] data_buffer (size) // constructs data_buffer object
Registering function [$02765838] utf8_buffer (string) // constructs data_buffer object from string encoded UTF8
Registering function [$02766174] web_packet ([bool use_gc]) // constructs void web_packed object
Registering function [$02767ED8] text_file (file_name, [mode], [code_page]) // opening text file and returns object
[16:56:17.175]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.476]. #DBG: Используется оригинальный обработчик VEH.
[16:56:17.476]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.476]. #DBG: _SetLogCB executed...
[16:56:17.476]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.478]. #REG_STUFF: Launcher = $0, Context = "_g.script"
[16:56:17.478]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.779]. #DBG: Используется оригинальный обработчик VEH.
[16:56:17.779]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.779]. #DBG: _SetLogCB executed...
[16:56:17.779]. #DBG: Setting AtPanic handler for lua_State g_game_lua_1
[16:56:17.779]. #DBG: jmp_byte at $1B2A3DBD = 204
[16:56:17.780] active_dialog updated to FALSE
[16:56:17.780]. #OK: class extensions found
[16:56:17.786/+25.334/940.8]. #XRAY: compiling shader sky2
[16:56:17.789/+25.337/3.024]. #XRAY: compiling shader sky2
[16:56:17.792/+25.340/3.078]. #XRAY: compiling shader clouds
[16:56:17.795/+25.343/2.702]. #XRAY: compiling shader clouds
[16:56:17.805/+25.353]. #PERF: pre-init complete!
[16:56:17.805/+00.001/ 10.7]. #XRAY: Starting engine...
[16:56:17.806/+00.002/0.886]. #XRAY: #PERF_WARN: CStringTable::Init () from 22084
[16:56:17.807/+00.003/0.646]. #XRAY: Init game globals...
[16:56:17.863/+00.059/ 55.9]. #XRAY: __super::OnAppStart...
[16:56:17.864/+00.060/0.722]. #XRAY: compiling shader stub_default
[16:56:17.866/+00.062/2.739]. #XRAY: compiling shader effects_sun
[16:56:17.870/+00.066/3.610]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\1.ogg
[16:56:17.870/+00.066/0.165]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\2.ogg
[16:56:17.870/+00.066/0.305]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\4.ogg
[16:56:17.871/+00.067/0.157]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\5.ogg
[16:56:17.871/+00.067/0.157]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\6.ogg
[16:56:17.871/+00.067/0.325]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\ambient\rnd_outdoor\8.ogg
[16:56:17.872/+00.068/1.142]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_2.ogg'
[16:56:17.872/+00.068/0.094]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_3.ogg'
[16:56:17.872/+00.068/0.092]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_4.ogg'
[16:56:17.872/+00.068/0.081]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_05.ogg'
[16:56:17.873/+00.069/0.085]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_06.ogg'
[16:56:17.873/+00.069/0.079]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_07.ogg'
[16:56:17.880/+00.076/7.089]. #XRAY: Can't find sound 'nature\ambient_bird_2.ogg'
[16:56:17.882/+00.078/1.832]. #XRAY: Can't find sound 'nature\ev_bird_11.ogg'
[16:56:17.883/+00.079/0.999]. #XRAY: Can't find sound 'nature\bird_even_1.ogg'
[16:56:17.883/+00.079/0.092]. #XRAY: Can't find sound 'nature\bird_even_2.ogg'
[16:56:17.895/+00.091/ 12.5]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_howl_1.ogg'
[16:56:17.896/+00.092/1.184]. #XRAY: Can't find sound 'ambient\rnd_outdoor\rnd_wolf_howl_06.ogg'
[16:56:17.897/+00.093/1.032]. #XRAY: Can't find sound 'nature\ev_bird_5.ogg'
[16:56:17.897/+00.093/0.091]. #XRAY: Can't find sound 'nature\ev_bird_8.ogg'
[16:56:17.898/+00.094/0.082]. #XRAY: Can't find sound 'nature\ev_bird_9.ogg'
[16:56:17.899/+00.095/1.132]. #XRAY: Can't find sound 'nature\bird_even_3.ogg'
[16:56:17.903/+00.099/4.375]. #XRAY: Can't find sound 'nature\art_morb_1.ogg'
[16:56:17.903/+00.099/0.097]. #XRAY: Can't find sound 'nature\art_morb_2.ogg'
[16:56:17.903/+00.099/0.084]. #XRAY: Can't find sound 'nature\art_morb_3.ogg'
[16:56:17.903/+00.099/0.082]. #XRAY: Can't find sound 'nature\art_morb_4.ogg'
[16:56:17.903/+00.099/0.080]. #XRAY: Can't find sound 'nature\art_morb_5.ogg'
[16:56:17.903/+00.099/0.080]. #XRAY: Can't find sound 'nature\art_morb_6.ogg'
[16:56:17.904/+00.100/0.080]. #XRAY: Can't find sound 'nature\art_morb_7.ogg'
[16:56:17.904/+00.100/0.082]. #XRAY: Can't find sound 'nature\art_morb_8.ogg'
[16:56:17.904/+00.100/0.077]. #XRAY: Can't find sound 'nature\art_morb_9.ogg'
[16:56:17.904/+00.100/0.079]. #XRAY: Can't find sound 'nature\art_morb_10.ogg'
[16:56:17.927/+00.123/ 23.5]. #XRAY: Can't find sound 'nature\mar_bird_5.ogg'
[16:56:17.927/+00.123/0.151]. #XRAY: Can't find sound 'nature\mar_bird_11.ogg'
[16:56:17.935/+00.131/7.856]. #XRAY: Can't find sound 'nature\mar_bird_1.ogg'
[16:56:17.935/+00.131/0.109]. #XRAY: Can't find sound 'nature\mar_bird_6.ogg'
[16:56:17.935/+00.131/0.087]. #XRAY: Can't find sound 'nature\mar_bird_7.ogg'
[16:56:17.936/+00.132/0.080]. #XRAY: Can't find sound 'nature\mar_bird_8.ogg'
[16:56:17.936/+00.132/0.079]. #XRAY: Can't find sound 'nature\mar_bird_9.ogg'
[16:56:17.936/+00.132/0.082]. #XRAY: Can't find sound 'nature\mar_bird_14.ogg'
[16:56:17.942/+00.138/6.217]. #XRAY: Can't find sound 'nature\ev_bird_2.ogg'
[16:56:17.942/+00.138/0.097]. #XRAY: Can't find sound 'nature\ev_bird_3.ogg'
[16:56:17.942/+00.138/0.106]. #XRAY: Can't find sound 'nature\ev_bird_4.ogg'
[16:56:17.942/+00.138/0.114]. #XRAY: Can't find sound 'nature\ev_bird_6.ogg'
[16:56:17.942/+00.138/0.094]. #XRAY: Can't find sound 'nature\ev_bird_10.ogg'
[16:56:17.943/+00.139/0.571]. #XRAY: Can't find sound 'nature\mar_bird_2.ogg'
[16:56:17.943/+00.139/0.089]. #XRAY: Can't find sound 'nature\mar_bird_3.ogg'
[16:56:17.943/+00.139/0.081]. #XRAY: Can't find sound 'nature\mar_bird_4.ogg'
[16:56:17.943/+00.139/0.092]. #XRAY: Can't find sound 'nature\mar_bird_10.ogg'
[16:56:17.948/+00.144/4.778]. #XRAY: Can't find sound 'nature\mar_bird_12.ogg'
[16:56:17.948/+00.144/0.095]. #XRAY: Can't find sound 'nature\mar_bird_13.ogg'
[16:56:17.951/+00.147/2.606]. #XRAY: Can't find sound 'ambient\rnd_outdoor\rnd_wolf_howl_05.ogg'
[16:56:17.967/+00.163/ 15.9]. #XRAY: Can't find sound 'ambient\rnd_outdoor\rnd_wolf_howl_07.ogg'
[16:56:17.967/+00.163/0.123]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_song_1.ogg'
[16:56:17.967/+00.163/0.089]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_song_2.ogg'
[16:56:17.967/+00.163/0.085]. #XRAY: Can't find sound 'ambient\rnd_outdoor\wolf_song_3.ogg'
[16:56:18.074/+00.270/106.7]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded info_sak_way.xml with 2658 items
[16:56:18.104/+00.300/ 30.7]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded info_portions_doc.xml with 207 items
[16:56:18.323/+00.519/218.9]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded info_original.xml with 782 items
[16:56:18.424/+00.620/100.4]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded storyline_info_to_diary.xml with 1293 items
[16:56:18.575/+00.771/151.8]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded dialogs_trading.xml with 1231 items
[16:56:18.583/+00.779/7.263]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded npc_profile.xml with 359 items
[16:56:18.595/+00.791/ 11.9]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded character_desc_sak.xml with 190 items
[16:56:18.603/+00.799/8.375]. #XRAY: #PERF: CSXML_IdToIndex::InitInternal loaded character_desc_simulation.xml with 116 items
[16:56:18.608/+00.804/5.288]. #XRAY: #PERF: Loaded 1940 weather cycles
[16:56:18.617/+00.813/8.538]. #XRAY: creating CMainMenu object
[16:56:18.630/+00.826/ 13.5]. #XRAY: #PERF: after CUIButtonHint()
[16:56:19.639/+01.835/ 1009]. #XRAY: #PERF_WARN: CStringTable::Init () loaded 42 files
[16:56:19.639/+01.835/0.031]. #XRAY: Hello world!
[16:56:19.655/+01.851/ 15.7]. #XRAY: #PERF_WARN: after func = 0x00566280 (rp_AppStart + 0x0), obj = 0x2BADF790 (unknown @ 0x2BADF790 + 0xfe09b4b0) frame elapsed = 26625 ms (+1843), ticks1 = 1844, ticks2 = 1844
[16:56:19.655/+01.851/0.036]. #XRAY: Runing message loop...
[16:56:19.657/+01.853/2.574]. #XRAY: "e:\nlc 3.8.2_release\bin\xr_3da.exe" -noprefetch -no_intro -verbosity 5 -smap2048 -verb_filters perf -debug_dialogs -mblur -start server(razoro_quicksave/single/alife/load) client(localhost)
[16:56:19.657/+01.853/0.040]. #XRAY: game render status switched to 'loading' from '::LoadBegin'
[16:56:19.663/+01.859/5.735]. #XRAY: Loading models...
[16:56:19.663/+01.859/0.041]. #XRAY: #PERF: CLevel::net_start1 end
[16:56:19.665]. #DBG: pRender = $71139A00, CRender::texture_load = $7106C380
[16:56:19.666]. #DBG: gTexList.List relocated to $1756605C
[16:56:19.704/+01.900/ 40.8]. #XRAY: phase time: 45 ms, user time: 0.0 ms, kernel time: 0.0 ms
[16:56:19.704/+01.900/0.041]. #XRAY: phase cmem: 688.684 M
[16:56:19.704/+01.900/0.023]. #XRAY: Next phase begins: Старт...
[16:56:19.736/+01.932/ 31.5]. #XRAY: #PERF: CLevel::net_start1 end
[16:56:19.752/+01.948/ 16.6]. #XRAY: CScriptEngine::init - (re)creating LUA virtual machine.
[16:56:19.755]. #DBG: garbage collection performed for LuaStateDesc object $29AA0D54 (LSD_g_game_lua_1@12940)
[16:56:19.755]. #DBG: performing unregister LSD_g_game_lua_1@12940 for lua_State g_game_lua_1
[16:56:19.755/main ($328c)(12940)]. #DBG: destroyed lua_State registry $29AA0D54 for Lparent = $00000000
[16:56:19.756/+01.952/3.662]. #XRAY: #PERF: destroying lua heap 0x2c3e0000, size = 0, blocks used = 0, block free = 412672
[16:56:19.756/+01.952/0.085]. #XRAY: #PERF: created lua heap 0x2e490000 for lua_State g_game_lua
[16:56:19.756/+01.952/0.180]. #XRAY: Lua Interceptor found! Attaching
[16:56:19.756]. #DBG: creating threads registry table.
[16:56:19.756]. #DBG: performing registration LSD_g_game_lua_2@12940 for lua_State g_game_lua_2
[16:56:19.756/12940/0.065]. #DBG: Added L = $2E50F5C8, captured in ExtCapture
[16:56:19.756]. #DBG: previous frealloc = nil, ud = $00000000,
#DBG: updated frealloc = $1B3845A0, ud = $2E490000,
[16:56:19.757]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:19.757]. #DBG: Complete CheckCapture for $2E50F5C8
[16:56:19.776]. #REG_STUFF: Launcher = $0, Context = "_g.script"
[16:56:19.776]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.077]. #DBG: Используется оригинальный обработчик VEH.
[16:56:20.077]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.077]. #DBG: _SetLogCB executed...
[16:56:20.077]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.080]. #REG_STUFF: Launcher = $0, Context = "_g.script"
[16:56:20.080]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.381]. #DBG: Используется оригинальный обработчик VEH.
[16:56:20.381]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.381]. #DBG: _SetLogCB executed...
[16:56:20.381]. #DBG: Setting AtPanic handler for lua_State g_game_lua_2
[16:56:20.381]. #DBG: jmp_byte at $1B2A3DBD = 204
[16:56:20.382] active_dialog updated to FALSE
[16:56:20.382]. #OK: class extensions found
[16:56:20.388/12940]. #DBG: For current thread not associated thread(child) lua_State - creating new = $00000000
[16:56:20.388]. #DBG: Dumping lua_State registry [$29AA0D54] for Lparent = g_game_lua_2
[16:56:20.388]. #DBG: for parent lua_State $2E50F5C8 associated thread-state $2EF58620, ThreadId = 12940
[16:56:20.417]. #DBG: xvars initialized! size = 0
[16:56:20.419/+02.615/662.6]. #XRAY: PERF: already opened ini-file e:\nlc 3.8.2_release\gamedata\config\system.ltx, count = 0
[16:56:20.499/+02.695/ 80.1]. #PERF: actor_binder init_stage = 1, context = mod_call('on_game_start')
[16:56:20.512/+02.708/ 13.1]. #XRAY: phase time: 807 ms, user time: 156.3 ms, kernel time: 0.0 ms
[16:56:20.512/+02.708/0.035]. #XRAY: phase cmem: 695.379 M
[16:56:20.512/+02.708/0.023]. #XRAY: Next phase begins: Загрузка симуляции жизни...
[16:56:20.519/+02.715/7.166]. #XRAY: phase time: 7 ms, user time: 0.0 ms, kernel time: 15.6 ms
[16:56:20.519/+02.715/0.034]. #XRAY: phase cmem: 697.211 M
[16:56:20.519/+02.715/0.023]. #XRAY: Next phase begins: Загрузка игры "razoro_quicksave.sav"...
[16:56:20.531/+02.727/ 11.7]. #XRAY: SE_DBG: CALifeSimulatorBase 'alife' ->m_objects = 0x2FF90A12
[16:56:20.587/+02.783/ 55.8]. #XRAY: Loading spawn registry...
[16:56:20.830/+03.026/242.8]. #XRAY: 13020 spawn points are successfully loaded
[16:56:20.830/+03.026/0.369]. #XRAY: Loading objects...
[16:56:20.902]. #SECTION_MAKER: создана капсула артефакта af_soul_capsule_dyn4d
[16:56:20.932]. #SECTION_MAKER: создана капсула артефакта af_soul_capsule_dyn1d
[16:56:20.976]. #SECTION_MAKER: создана капсула артефакта af_dummy_glassbeads_capsule
[16:56:21.030]. #SECTION_MAKER: создана капсула артефакта af_dummy_battery_capsule
[16:56:21.083/+03.279/253.2]. #XRAY: 20759 objects are successfully loaded
[16:56:21.107/+03.303/ 23.7]. #DBG: Root registry obj. count = 5000
[16:56:21.121/+03.317/ 14.0]. #DBG: Root registry obj. count = 10000
[16:56:21.134/+03.330/ 12.9]. #DBG: Root registry obj. count = 15000
[16:56:21.153/+03.349/ 19.6]. #DBG: Root registry obj. count = 20000
[16:56:21.178/+03.374/ 24.3]. #XRAY: 2050 script vars are successfully loaded
[16:56:21.298/+03.494/120.2]. #XRAY: #WARN: CLocatorAPI::check_for_file not found file e:\nlc 3.8.2_release\gamedata\config\e:\nlc 3.8.2_release\gamedata\config\balance_light.ltx in files list (size = 48931)
[16:56:22.219/+04.415/920.7]. #XRAY: Game razoro_quicksave is successfully loaded from file 'e:\nlc 3.8.2_release\profiles\razoro\saves\razoro_quicksave.sav' (1.705s)
[16:56:22.227/+04.423/7.957]. #XRAY: phase time: 1707 ms, user time: 1421.9 ms, kernel time: 78.1 ms
[16:56:22.227/+04.423/0.036]. #XRAY: phase cmem: 909.512 M
[16:56:22.227/+04.423/0.022]. #XRAY: Next phase begins: Соединяемся...
[16:56:22.437/+04.633/210.2]. #XRAY: MaxPlayers = 32
[16:56:22.458/+04.654/ 21.1]. #XRAY: #PERF: CLevel::net_start2 end
[16:56:22.477/+04.673/ 18.8]. #XRAY: #PERF: CLevel::net_start3 end
[16:56:22.526/+04.722/ 49.7]. #XRAY: #PERF: CLevel::net_start4 end
[16:56:22.750/+04.946/223.6]. #XRAY: phase time: 523 ms, user time: 250.0 ms, kernel time: 15.6 ms
[16:56:22.750/+04.946/0.041]. #XRAY: phase cmem: 927.695 M
[16:56:22.750/+04.946/0.024]. #XRAY: Next phase begins: Соединение с localhost...
[16:56:22.759/+04.955/8.697]. #XRAY: #PERF: CLevel::net_start_client1 end
[16:56:22.809/+05.005/ 50.6]. #XRAY: Player not found. New player created.
[16:56:22.809/+05.005/0.051]. #XRAY: client : connection accepted - <>
[16:56:22.809/+05.005/0.033]. #XRAY: #PERF: CLevel::net_start_client2 end
[16:56:22.851/+05.047/ 41.4]. #XRAY: phase time: 100 ms, user time: 15.6 ms, kernel time: 0.0 ms
[16:56:22.851/+05.047/0.038]. #XRAY: phase cmem: 928.328 M
[16:56:22.851/+05.047/0.021]. #XRAY: Next phase begins: Открытие потока...
[16:56:22.868/+05.064/ 16.9]. #XRAY: phase time: 16 ms, user time: 0.0 ms, kernel time: 0.0 ms
[16:56:22.868/+05.064/0.035]. #XRAY: phase cmem: 931.484 M
[16:56:22.868/+05.064/0.024]. #XRAY: Next phase begins: Загрузка формы объектов...
[16:56:23.689/+05.885/820.8]. #XRAY: phase time: 820 ms, user time: 781.2 ms, kernel time: 31.3 ms
[16:56:23.689/+05.885/0.041]. #XRAY: phase cmem: 985.715 M
[16:56:23.689/+05.885/0.057]. #XRAY: Next phase begins: Загрузка шейдеров...
[16:56:23.690/+05.886/1.103]. #XRAY: compiling shader deffer_base_aref_bump_d-hq
[16:56:23.694/+05.890/4.699]. #XRAY: compiling shader deffer_base_bump_d-hq
[16:56:23.698/+05.894/3.564]. #XRAY: compiling shader deffer_base_aref_bump
[16:56:23.702/+05.898/3.798]. #XRAY: compiling shader deffer_base_bump
[16:56:23.705/+05.901/3.612]. #XRAY: compiling shader shadow_direct_base_aref
[16:56:23.708/+05.904/2.560]. #XRAY: compiling shader shadow_direct_base_aref
[16:56:23.711/+05.907/2.780]. #XRAY: compiling shader deffer_base_bump_d-hq
[16:56:23.715/+05.911/4.451]. #XRAY: compiling shader deffer_base_bump
[16:56:23.719/+05.915/3.828]. #XRAY: compiling shader shadow_direct_base
[16:56:23.722/+05.918/2.621]. #XRAY: compiling shader deffer_base_aref_bump_db-hq
[16:56:23.726/+05.922/4.767]. #XRAY: compiling shader deffer_base_bump_db-hq
[16:56:23.731/+05.927/4.943]. #XRAY: compiling shader deffer_base_flat
[16:56:23.735/+05.931/3.526]. #XRAY: compiling shader deffer_base_flat
[16:56:23.738/+05.934/3.074]. #XRAY: compiling shader deffer_base_steep_db-hq
[16:56:23.751/+05.947/ 13.5]. #XRAY: compiling shader deffer_base_flat_d
[16:56:23.755/+05.951/3.665]. #XRAY: compiling shader deffer_base_flat_d
[16:56:23.771/+05.967/ 15.5]. #XRAY: compiling shader deffer_base_bump-hq
[16:56:23.775/+05.971/4.143]. #XRAY: compiling shader deffer_base_bump-hq
[16:56:23.778/+05.974/3.471]. #XRAY: compiling shader deffer_base_aref_flat
[16:56:23.782/+05.978/3.686]. #XRAY: compiling shader base_lplanes
[16:56:23.785/+05.981/2.968]. #XRAY: compiling shader base_lplanes
[16:56:23.789/+05.985/3.749]. #XRAY: compiling shader simple
[16:56:23.791/+05.987/2.416]. #XRAY: compiling shader wmark
[16:56:23.795/+05.991/3.737]. #XRAY: compiling shader deffer_base_lmh_bump_db-hq
[16:56:23.800/+05.996/4.756]. #XRAY: compiling shader deffer_base_lmh_bump_d-hq
[16:56:23.803/+05.999/3.613]. #XRAY: compiling shader deffer_base_lmh_bump
[16:56:23.807/+06.003/3.722]. #XRAY: compiling shader deffer_base_lmh_bump
[16:56:23.811/+06.007/3.759]. #XRAY: compiling shader deffer_base_lmh_bump_d-hq
[16:56:23.815/+06.011/4.668]. #XRAY: compiling shader deffer_base_lmh_flat_d
[16:56:23.819/+06.015/3.716]. #XRAY: compiling shader deffer_base_lmh_flat_d
[16:56:23.822/+06.018/3.288]. #XRAY: compiling shader deffer_base_lmh_flat
[16:56:23.826/+06.022/3.466]. #XRAY: compiling shader deffer_base_lmh_flat
[16:56:23.832/+06.028/6.290]. #XRAY: compiling shader deffer_base_lmh_steep_db-hq
[16:56:23.847/+06.043/ 15.0]. #XRAY: compiling shader deffer_base_lmh_aref_bump_d-hq
[16:56:23.852/+06.048/4.716]. #XRAY: compiling shader deffer_base_lmh_aref_bump
[16:56:23.856/+06.052/4.240]. #XRAY: compiling shader deffer_base_lmh_bump-hq
[16:56:23.861/+06.057/4.913]. #XRAY: compiling shader deffer_base_lmh_bump-hq
[16:56:23.865/+06.061/4.015]. #XRAY: compiling shader deffer_base_lmh_aref_bump-hq
[16:56:23.871/+06.067/6.488]. #XRAY: compiling shader deffer_base_lmh_steep_d-hq
[16:56:23.884/+06.080/ 12.4]. #XRAY: compiling shader deffer_base_lmh_aref_bump_db-hq
[16:56:23.892/+06.088/8.409]. #XRAY: compiling shader deffer_impl_flat_d
[16:56:23.897/+06.093/5.012]. #XRAY: compiling shader deffer_impl_flat_d
[16:56:23.901/+06.097/3.635]. #XRAY: compiling shader deffer_impl_flat
[16:56:23.905/+06.101/3.769]. #XRAY: compiling shader water_soft
[16:56:23.910/+06.106/5.549]. #XRAY: compiling shader water_soft
[16:56:23.916/+06.112/5.802]. #XRAY: compiling shader waterd_soft
[16:56:23.919/+06.115/3.225]. #XRAY: compiling shader waterd_soft
[16:56:23.923/+06.119/4.247]. #XRAY: compiling shader deffer_tree_bump_d-hq
[16:56:23.927/+06.123/4.000]. #XRAY: compiling shader deffer_tree_bump
[16:56:23.931/+06.127/3.908]. #XRAY: compiling shader shadow_direct_base
[16:56:23.935/+06.131/3.137]. #XRAY: compiling shader shadow_direct_tree
[16:56:23.938/+06.134/3.138]. #XRAY: compiling shader deffer_tree_flat
[16:56:23.942/+06.138/4.675]. #XRAY: compiling shader deffer_tree_s_bump_d-hq
[16:56:23.946/+06.142/3.627]. #XRAY: compiling shader deffer_tree_s_bump
[16:56:23.949/+06.145/3.510]. #XRAY: compiling shader shadow_direct_tree_s
[16:56:23.952/+06.148/2.867]. #XRAY: compiling shader deffer_tree_s_flat_d
[16:56:23.956/+06.152/3.268]. #XRAY: compiling shader deffer_tree_s_flat
[16:56:23.959/+06.155/3.335]. #XRAY: compiling shader deffer_tree_s_bump-hq
[16:56:23.973/+06.169/ 13.8]. #XRAY: phase time: 284 ms, user time: 171.9 ms, kernel time: 93.8 ms
[16:56:23.973/+06.169/0.056]. #XRAY: phase cmem: 990.742 M
[16:56:23.973/+06.169/0.028]. #XRAY: Next phase begins: Загрузка геометрии...
[16:56:24.009/+06.205/ 36.2]. #XRAY: phase time: 36 ms, user time: 15.6 ms, kernel time: 15.6 ms
[16:56:24.009/+06.205/0.039]. #XRAY: phase cmem: 1061.820 M
[16:56:24.009/+06.205/0.025]. #XRAY: Next phase begins: Загрузка базы пространств...
[16:56:24.018/+06.214/8.639]. #XRAY: #PERF: loading 1500 visual/mesh...
[16:56:24.025/+06.221/7.294]. #XRAY: #PERF: loading 3000 visual/mesh...
[16:56:24.032/+06.228/6.498]. #XRAY: #PERF: loading 4500 visual/mesh...
[16:56:24.038/+06.234/6.600]. #XRAY: #PERF: loading 6000 visual/mesh...
[16:56:24.045/+06.241/6.618]. #XRAY: #PERF: loading 7500 visual/mesh...
[16:56:24.051/+06.247/6.366]. #XRAY: #PERF: loading 9000 visual/mesh...
[16:56:24.058/+06.254/6.501]. #XRAY: #PERF: loading 10500 visual/mesh...
[16:56:24.063/+06.259/4.962]. #XRAY: #PERF: loading 12000 visual/mesh...
[16:56:24.067/+06.263/4.669]. #XRAY: #PERF: loading 13500 visual/mesh...
[16:56:24.071/+06.267/4.125]. #XRAY: #PERF: loaded 14559 visuals, last_loaded = 0,
[16:56:24.080/+06.276/8.258]. #XRAY: phase time: 70 ms, user time: 62.5 ms, kernel time: 15.6 ms
[16:56:24.080/+06.276/0.028]. #XRAY: phase cmem: 1066.199 M
[16:56:24.080/+06.276/0.022]. #XRAY: Next phase begins: Загрузка детальных объектов...
[16:56:24.082/+06.278/2.060]. #XRAY: compiling shader deffer_detail_w_flat
[16:56:24.087/+06.283/5.324]. #XRAY: compiling shader deffer_detail_s_flat
[16:56:24.094/+06.290/7.199]. #XRAY: [DETAILS] VertexConsts(256), Batch(61)
[16:56:24.094/+06.290/0.043]. #XRAY: [DETAILS] 40687 v(20), 26352 p
[16:56:24.094/+06.290/0.105]. #XRAY: [DETAILS] Batch(61), VB(794K), IB(154K)
[16:56:24.104/+06.300/9.097]. #XRAY: phase time: 23 ms, user time: 15.6 ms, kernel time: 0.0 ms
[16:56:24.104/+06.300/0.033]. #XRAY: phase cmem: 1069.328 M
[16:56:24.104/+06.300/0.023]. #XRAY: Next phase begins: Загрузка секторов и порталов...
[16:56:24.111/+06.307/7.313]. #XRAY: Loading HOM: e:\nlc 3.8.2_release\gamedata\levels\l04_darkvalley\level.hom
[16:56:24.121/+06.317/9.663]. #XRAY: phase time: 16 ms, user time: 0.0 ms, kernel time: 0.0 ms
[16:56:24.121/+06.317/0.034]. #XRAY: phase cmem: 1069.438 M
[16:56:24.121/+06.317/0.024]. #XRAY: Next phase begins: Загрузка ИИ объектов...
[16:56:24.134/+06.330/ 13.7]. #XRAY: #PERF: CLevel::net_start_client3 end
[16:56:24.152/+06.348/ 17.8]. #XRAY: phase time: 31 ms, user time: 0.0 ms, kernel time: 0.0 ms
[16:56:24.152/+06.348/0.033]. #XRAY: phase cmem: 1066.895 M
[16:56:24.152/+06.348/0.022]. #XRAY: Next phase begins: Создание...
[16:56:24.173/+06.369/ 20.8]. #XRAY: Game configuring : Started
[16:56:24.175/+06.371/1.587]. #XRAY: Game configuring : Finished
[16:56:24.180/+06.376/5.388]. #XRAY: #PERF: CLevel::net_start_client4 end
[16:56:24.238/+06.434/ 58.0]. #XRAY: phase time: 85 ms, user time: 46.9 ms, kernel time: 15.6 ms
[16:56:24.238/+06.434/0.039]. #XRAY: phase cmem: 1085.109 M
[16:56:24.238/+06.434/0.021]. #XRAY: Next phase begins: Загрузка текстур...
[16:56:30.260/+12.456/ 6022]. #XRAY: #PERF: IGame_Level::LL_CheckTextures()
[16:56:30.260/+12.456/0.113]. #XRAY: t-report - base: 1572, 1259748 K
[16:56:30.260/+12.456/0.025]. #XRAY: t-report - lmap: 8, 8193 K
[16:56:30.260/+12.456/0.025]. #XRAY: **WARNING***: Too many base-textures (limit: 2000 textures or 1024M).
[16:56:30.260/+12.456/0.022]. #XRAY: Reduce number of textures (better) or their resolution (worse).
[16:56:30.260/+12.456/0.021]. #XRAY: #PERF: CLevel::net_start_client5 end
[16:56:30.281/+12.477/ 20.6]. #XRAY: phase time: 6042 ms, user time: 5656.3 ms, kernel time: 375.0 ms
[16:56:30.281/+12.477/0.050]. #XRAY: phase cmem: 2413.082 M
[16:56:30.281/+12.477/0.028]. #XRAY: Next phase begins: Синхронизация...
[16:56:30.327/+12.523/ 46.4]. #XRAY: #PERF: CLevel::net_start_client6 end
[16:56:30.395/+12.591/ 67.6]. #XRAY: #PERF: CLevel::net_start5 end
[16:56:30.461/+12.657/ 65.7]. #XRAY: @---------- CApplication::LoadEnd --------------
[16:56:30.461/+12.657/0.031]. #XRAY: phase time: 179 ms
[16:56:30.480/+12.676/ 19.7]. #XRAY: phase cmem: 2413.082 M
[16:56:30.481/+12.677/0.167]. #XRAY: #PERF: CLevel::net_start6 end
[16:56:30.498/+12.694/ 17.6]. #XRAY: #PERF: game_events->queue.size() returned 1142
[16:56:30.500/+12.696/1.901]. #XRAY: #DEBUG: KNIFE_SLOT (0) persistent = false
[16:56:30.500/+12.696/0.029]. #XRAY: #DEBUG: PISTOL_SLOT (1) persistent = false
[16:56:30.500/+12.696/0.022]. #XRAY: #DEBUG: RIFLE_SLOT (2) persistent = false
[16:56:30.500/+12.696/0.020]. #XRAY: #DEBUG: GRENADE_SLOT (3) persistent = false
[16:56:30.500/+12.696/0.020]. #XRAY: #DEBUG: APPARATUS_SLOT (4) persistent = false
[16:56:30.500/+12.696/0.020]. #XRAY: #DEBUG: BOLT_SLOT (5) persistent = true
[16:56:30.500/+12.696/0.020]. #XRAY: #DEBUG: OUTFIT_SLOT (6) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: PDA_SLOT (7) persistent = false
[16:56:30.500/+12.696/0.022]. #XRAY: #DEBUG: DETECTOR_SLOT (8) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: TORCH_SLOT (9) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: ARTEFACE_SLOT (10) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: HELMET_SLOT (11) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: SLOT_QUICK_ACCESS_0 (12) persistent = false
[16:56:30.500/+12.696/0.022]. #XRAY: #DEBUG: SLOT_QUICK_ACCESS_1 (13) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: SLOT_QUICK_ACCESS_2 (14) persistent = false
[16:56:30.500/+12.696/0.021]. #XRAY: #DEBUG: SLOT_QUICK_ACCESS_3 (15) persistent = false
[16:56:30.501/+12.697/0.531]. #XRAY: compiling shader deffer_model_bump-hq_2
[16:56:30.509/+12.705/8.635]. #XRAY: compiling shader deffer_model_bump_2
[16:56:30.518/+12.714/8.546]. #XRAY: compiling shader shadow_direct_model_2
[16:56:30.525/+12.721/6.567]. #XRAY: compiling shader deffer_model_flat_2
[16:56:30.532/+12.728/7.369]. #XRAY: #PERF_WARN: FHierrarhyVisual load from actors\hero_fix\without elapsed time = 31.2 ms
[16:56:30.716/+12.912/183.9]. #XRAY: phase time: 434 ms, user time: 187.5 ms, kernel time: 46.9 ms
[16:56:30.716/+12.912/0.041]. #XRAY: phase cmem: 2448.285 M
[16:56:30.716/+12.912/0.026]. #XRAY: Next phase begins: "Чёрная метка" — это паразит, который жизнью твоей питается...
[16:56:30.718/+12.914/1.893]. #XRAY: created CScriptBinderObjectWrapper 4DA3C302 for single_player
[16:56:30.718/+12.914/0.106]. #PERF: actor_binder init_stage = 2, context = ?
[16:56:30.718]. #DBG: xrGame.DLL detection = 7520
[16:56:30.718]. #DBG: Hyperinflation begins...
[16:56:30.718]. #DBG: Hyperinflation complete
[16:56:30.722]. #DBG: Protected script (re)loaded...
[16:56:30.722/+12.918/4.031]. #PERF: actor_binder init_stage = 3, context = ?
[16:56:30.722]. #DBG: _SetLogCB executed...
[16:56:30.722/0.207]. #DBG: actor_binder:load start, packet pos = 45, elapsed = 5585
[16:56:30.738/ 15.5]. #DBG: actor_binder:load end
[16:56:30.738/+12.934/0.018]. #XRAY: #PERF: load processed for single_player, work_time = 15.6 ms
[16:56:30.738/+12.934/0.131]. #PERF: actor_binder init_stage = 4, context = ?
[16:56:30.738]. #DBG: added actor single_player
[16:56:30.738/+12.934/0.218]. #PERF: actor_binder init_stage = 5, context = ?
[16:56:30.738/0.084]. #DBG: db.actor present
[16:56:30.739]. #DBG: task_smart_upd - reseting task_list
[16:56:30.739]. #DBG: updating regular task list...
[16:56:30.753/ 15.2]. #PERF: params.script 0
[16:56:30.754/0.149]. #PERF: params.script 1
[16:56:30.754/0.064]. #DBG: TObjOffsets trying to load file E:\NLC 3.8.2_release\bin\xray_dma.conf
[16:56:30.758/4.035]. #DBG: builds count = 131
[16:56:30.774/ 16.4]. #DBG: offset map size = 54
[16:56:30.838]. #DBG: DMA offsets loaded from section build_7520, id_word = 168
[16:56:30.838/ 64.3]. #PERF: params.script 2
[16:56:30.843]. #DBG: DetectPointers, g_pGameLevel = 308BBC80, check offsets $4C, +88, +100
[16:56:30.843/+13.039/4.759]. #DBG: creating BackgroundScanner thread.
[16:56:30.843]. #DBG: SetXrPointer updating objects_active from $00000000 to $308BBCD8
[16:56:30.843]. #DBG: SetXrPointer updating objects_sleeping from $00000000 to $308BBCE4
[16:56:30.843]. #DBG: SetXrPointer updating client_objects from $00000000 to $30520050
[16:56:30.843]. #DBG: g_ai_space exported at xrGame.dll + $C1EF68
[16:56:30.843]. g_ai_space[$18] = $1AB981BA, alife = $1A8920B0, sim_base = $1A892544, obj_reg = $2FF90A12, spawn_reg = $30EEBB12
[16:56:30.843]. #DBG: SetXrPointer updating alife_objects from $00000000 to $2FF90A16
[16:56:30.843]. #DBG: SetXrPointer updating alife_spawns from $00000000 to $30EEBB5E
[16:56:30.843]. #DBG: SetXrPointer updating alife_st_ids from $00000000 to $30EEBBA6
[16:56:30.844]. #DBG: WorkerThread 17920 ($4600), Name = BackgroundScanner { TBackgroundScanner@=$0F89B524:: luaicp.dll /ID=17920 }, Class = TBackgroundScanner started std-loop in OleExec
[16:56:30.844/0.841]. #PERF: loading spawn points start...
[16:56:30.851/7.022]. #DBG: from spawn_pos.ltx loaded 13516 lines, file size = 421531
[16:56:30.864/ 13.4]. #DBG: after sieve rest 124 spawn points
[16:56:30.865/0.844]. #DBG: updating spawn_map from root = $2FF909F2
[16:56:30.927/ 62.3]. #DBG: sorting spawn_map.
[16:56:31.017/ 89.2]. #DBG: complete spawn_map. Items = 26040
[16:56:31.018/+13.214/1.644]. #DBG: scan_found server objects = 20759
[16:56:31.018]. #PERF_WARN(ScanObjects): 0.2 + 174.6 + 0.0 + 0.0 + 0.1 = 174.9 msec, ok_count = 0
[16:56:31.023/4.324]. #PERF: Direct VMScan regions checked 1000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.024]. #DBG: for object repair_boxoutfit43779 (43779) parent changed from 65535 to 1833, ctx = LoadState, online = no
[16:56:31.026/3.388]. #PERF: Direct VMScan regions checked 2000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.029/3.072]. #PERF: Direct VMScan regions checked 3000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.032/3.095]. #PERF: Direct VMScan regions checked 4000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.036/3.758]. #PERF: Direct VMScan regions checked 5000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.039/3.266]. #PERF: Direct VMScan regions checked 6000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.043/3.723]. #PERF: Direct VMScan regions checked 7000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.046/3.539]. #PERF: Direct VMScan regions checked 8000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.051/4.334]. #PERF: Direct VMScan regions checked 9000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.056/5.151]. #PERF: Direct VMScan regions checked 10000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.059]. #DBG: for object repair_itemoutfit_feik26919 (26919) parent changed from 65535 to 8227, ctx = LoadState, online = no
[16:56:31.059]. #DBG: for object repair_itemoutfit_feik26918 (26918) parent changed from 65535 to 8227, ctx = LoadState, online = no
[16:56:31.059]. #DBG: for object repair_itemoutfit_feik26917 (26917) parent changed from 65535 to 8227, ctx = LoadState, online = no
[16:56:31.059]. #DBG: for object repair_item_weapon_feik26916 (26916) parent changed from 65535 to 8227, ctx = LoadState, online = no
[16:56:31.059]. #DBG: for object repair_item_weapon_feik26915 (26915) parent changed from 65535 to 8227, ctx = LoadState, online = no
[16:56:31.060/4.083]. #PERF: Direct VMScan regions checked 11000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.064/4.341]. #PERF: Direct VMScan regions checked 12000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.068/3.402]. #PERF: Direct VMScan regions checked 13000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.070/2.618]. #PERF: Direct VMScan regions checked 14000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.074/4.019]. #PERF: Direct VMScan regions checked 15000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.078/3.974]. #PERF: Direct VMScan regions checked 16000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.082/3.539]. #PERF: Direct VMScan regions checked 17000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.085/3.208]. #PERF: Direct VMScan regions checked 18000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.088/2.839]. #PERF: Direct VMScan regions checked 19000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.091/3.341]. #PERF: Direct VMScan regions checked 20000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.095/3.706]. #PERF: Direct VMScan regions checked 21000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.099/3.694]. #PERF: Direct VMScan regions checked 22000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.104/5.511]. #PERF: Direct VMScan regions checked 23000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.109/5.196]. #PERF: Direct VMScan regions checked 24000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.114/4.937]. #PERF: Direct VMScan regions checked 25000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.118/4.195]. #PERF: Direct VMScan regions checked 26000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.123/4.304]. #PERF: Direct VMScan regions checked 27000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.127/4.113]. #PERF: Direct VMScan regions checked 28000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.131/4.211]. #PERF: Direct VMScan regions checked 29000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.135/4.064]. #PERF: Direct VMScan regions checked 30000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.139/3.932]. #PERF: Direct VMScan regions checked 31000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.142/3.150]. #PERF: Direct VMScan regions checked 32000, g_scanner.scan_loop = 1, bad_ptrs = 0
[16:56:31.146/3.534]. #PERF: Direct VMScan regions checked 33000, g_scanner.scan_loop = 2, bad_ptrs = 0
[16:56:31.150/3.955]. #PERF: Direct VMScan regions checked 34000, g_scanner.scan_loop = 2, bad_ptrs = 0
[16:56:31.153/3.419]. #PERF: Direct VMScan regions checked 35000, g_scanner.scan_loop = 2, bad_ptrs = 0
[16:56:31.157/4.074]. #PERF: Direct VMScan regions checked 36000, g_scanner.scan_loop = 2, bad_ptrs = 0
[16:56:31.323/166.0/12940]. #PERF_WARN(TRootRegistry.Update): so(0) = 174930, ap = 261, la = 296946, um = 7621 mcs add = 20759, chk = 20760 upd = 20759, heavy = 20760, loop = $1 [480 ms]
added = 20759, reg_events = 20759, destr_events = 1142
addr range = [$1674E000 .. $4EC6F000]
stack traceback:
...3.8.2_release\gamedata\scripts\alpet\registry.script:202: in function 'init'
...3.8.2_release\gamedata\scripts\alpet\registry.script:29: in function 'check_init'
...3.8.2_release\gamedata\scripts\alpet\registry.script:485: in function 'inv_objects'
... 3.8.2_release\gamedata\scripts\arts\arc_arts.script:271: in function 'check_artefact'
... 3.8.2_release\gamedata\scripts\arts\arc_arts.script:487: in main chunk
[C]: ?
....2_release\gamedata\scripts\sak\sak_inventory.script:130: in function 'need_update_inventory'
..._release\gamedata\scripts\arts\arc_containers.script:453: in function 'check_container'
..._release\gamedata\scripts\arts\arc_containers.script:523: in main chunk
[C]: ?
....2_release\gamedata\scripts\vergas\vergas_lib.script:294: in main chunk
[C]: ?
...release\gamedata\scripts\alpet\task_smart_upd.script:190: in function 'InitBinderTaskList'
...release\gamedata\scripts\alpet\task_smart_upd.script:417: in main chunk
[C]: ?
...release\gamedata\scripts\binders\bind_stalker.script:583: in function 'safe_net_spawn'
...release\gamedata\scripts\binders\bind_stalker.script:572: in function <...release\gamedata\scripts\binders\bind_stalker.script:546>
#WARN: load_mask changed to $1F
max_belt updated to 0
CTexture.SetName(ui\amk_ui_base_textur0), old = ui\amk_ui_base_texture
after load texture name = ui\amk_ui_base_textur0
[16:56:31.328]. #DBG: arc_arts version '24.08.2021 00:19' check_artefact() results:
{
}
{
}
[16:56:31.331]. #DBG: updating regular task list...
[16:56:31.332/+13.528/8.317]. #PERF: actor_binder init_stage = 6, context = ?
[16:56:31.336/+13.532/4.160]. #XRAY: Invalid ogg-comment version, file: e:\nlc 3.8.2_release\gamedata\sounds\characters_voice\scenario\agroprom\krot_help_pda_2.ogg
[16:56:31.354] #DBG: g_pStringContainer = $0F8EFDC2
[16:56:31.354] #DBG: str_container::dock = $042D2130
[16:56:31.354] #DBG: str_container::dump = $042D2462
[16:56:31.396]. #REG_STUFF: Launcher = $0, Context = "render.script"
[16:56:31.396]. #DBG: Setting AtPanic handler for lua_State g_aux_lua_2
[16:56:31.697]. #DBG: Используется оригинальный обработчик VEH.
[16:56:31.721]. #DBG: modmgmt.check_scripts processed 435 scripts from 30 folders
[16:56:31.721/+13.917/385.7]. #PERF: actor_binder init_stage = 7, context = ?
[16:56:31.721]. #DBG: loading byte code for savemgmt
#TEST0: gettop = 1
#TEST1: gettop = 4
#TEST2: gettop = 4
in stack at -2 now "function" (6)
in stack at -1 now "function" (6)
#DBG: yep = false
[16:56:31.722]. #DBG: neytral load complete 0
[16:56:31.780]. #DBG: loading byte code for hidden
#TEST0: gettop = 1
[16:56:31.780]. #DBG: namespace not exists - creating...
#TEST1: gettop = 4
#TEST2: gettop = 4
in stack at -2 now "function" (6)
in stack at -1 now "function" (6)
[16:56:31.780]. #DBG: loading byte code for profiles
#TEST0: gettop = 1
#TEST1: gettop = 4
#TEST2: gettop = 4
in stack at -2 now "function" (6)
in stack at -1 now "function" (6)
[16:56:31.781/+13.977/ 59.1]. #PERF: actor_binder init_stage = 8, context = ?
[16:56:31.801/+13.997/ 20.1]. #XRAY: phase time: 1082 ms, user time: 625.0 ms, kernel time: 156.3 ms
[16:56:31.801/+13.997/0.038]. #XRAY: phase cmem: 2389.000 M
[16:56:31.801/+13.997/0.023]. #XRAY: Next phase begins: #init_registry
[16:56:31.803/+13.999/2.836]. #DBG: scan_found server objects = 20759
[16:56:31.842/ 38.8/12940]. #PERF_WARN(TRootRegistry.Update): so(0) = 1943, ap = 4205, la = 34448, um = 0 mcs add = 0, chk = 20760 upd = 0, heavy = 20760, loop = $2 [41 ms]
added = 0, reg_events = 0, destr_events = 0
addr range = [$1674E000 .. $4EC6F000]
stack traceback:
...3.8.2_release\gamedata\scripts\alpet\registry.script:202: in function 'init'
...release\gamedata\scripts\binders\bind_stalker.script:595: in function 'safe_net_spawn'
...release\gamedata\scripts\binders\bind_stalker.script:572: in function <...release\gamedata\scripts\binders\bind_stalker.script:546>
#WARN: load_mask changed to $3F
[16:56:31.842/+14.038/0.157]. #PERF: actor_binder init_stage = 9, context = ?
[16:56:31.864]. #DBG: Version control dump:
bind_stalker.script / alpet / 16.01.2016 18:15 : Оптимизация
[16:56:31.864/+14.060/ 22.0]. #PERF: actor_binder init_stage = 10, context = ?
[2 окт 16:56:31.865/main ($328c)(12940)]. #DBG: Cheat protection activated. Common key = {7E192-CB879-D5E60-00D86-F6C8C-5ECE1-FE6F9-877E1}
[16:56:31.865]. #DBG: Setting AtPanic handler for lua_State g_aux_lua_2
[16:56:31.865/1.017]. #DBG: registering bind_stalker regular tasks...
[16:56:31.969/+14.165/103.3]. #XRAY: phase time: 167 ms, user time: 140.6 ms, kernel time: 31.3 ms
[16:56:31.969/+14.165/0.041]. #XRAY: phase cmem: 2401.109 M
[16:56:31.969/+14.165/0.021]. #XRAY: Next phase begins: #init_modules
[16:56:31.978]. #WARN: script xr_evtors_id didnt autoloaded, trying force...
[16:56:31.978]. #DBG: performing registration LSD_L1_2@12940 for lua_State L1_2
[16:56:31.978/12940]. #DBG: For current thread not associated thread(child) lua_State - creating new = $00000000
[16:56:31.978]. #DBG: Dumping lua_State registry [$29AA1814] for Lparent = L1_2
[16:56:31.978]. #DBG: for parent lua_State $2EF58620 associated thread-state $ACA84D38, ThreadId = 12940
[16:56:31.978/$2EF58620/$ACA84D38]. #DBG: LuaSafeCall ("e:\nlc 3.8.2_release\gamedata\scripts\xr\xr_evaluators_id.script", "#file:^dbg"), extra-args = 0
[16:56:31.979]. #DOFILE: result = File not found: e:\nlc 3.8.2_release\gamedata\scripts\xr\xr_evaluators_id.script
[16:56:31.983]. #DBG: modmgmt.check_scripts processed 435 scripts from 30 folders
[16:56:31.984]. #DBG: trying invoke init_module for modules schedule,misc,smart_terrain,amk_mod,vergas_lib,balance,campmgr,xray_vars,black_tip,const,registry,nvd_params,savemgmt,bind_nlc,wpn_params,razgruzka,sak_repair_dialog,sak_dialog,params,nlc_container,xr_meet,task_smart_upd,xr_statistic,hud_params,watcher_act,sak_inventory,kanistra_flame,bind_stalker,flamethrower,sak,class_info,arc_arts,profiles,lightman,xray_patch,darkvalley_tasks,windows,actor_params,vergas_watch,vergas_krujka,linker
good mode !
[16:56:31.985/+14.181/ 15.8]. #XRAY: PERF: already opened ini-file e:\nlc 3.8.2_release\gamedata\config\balance_light.ltx, count = 0
xr_3da.exe size = 2445312, xrGame.dll size = 0
game_graph version = 8, vertex_cnt = 4544, edge_cnt = 11170, lpt_cnt = 590242, level_count = 36
vertex_offset = $6F1, edge_offset = $2F071, lpt_offset = $3F63D
[16:56:32.026]. #DBG: table _G items count = 1312
good mode !
[16:56:32.028]. #DBG: Таймер обновления биндера установлен в 300000
[16:56:32.028/+14.224/ 43.2]. #PERF: actor_binder init_stage = 11, context = ?
[16:56:32.048/+14.244/ 20.0]. #XRAY: phase time: 78 ms, user time: 31.2 ms, kernel time: 46.9 ms
[16:56:32.048/+14.244/0.037]. #XRAY: phase cmem: 2401.156 M
[16:56:32.048/+14.244/0.022]. #XRAY: Next phase begins: #after_init
[16:56:32.049]. #DBG: trying invoke after_init for modules schedule,misc,smart_terrain,amk_mod,vergas_lib,balance,campmgr,xray_vars,black_tip,const,registry,nvd_params,savemgmt,bind_nlc,wpn_params,razgruzka,sak_repair_dialog,sak_dialog,params,nlc_container,xr_meet,task_smart_upd,xr_statistic,hud_params,watcher_act,sak_inventory,kanistra_flame,bind_stalker,flamethrower,sak,class_info,arc_arts,profiles,lightman,xray_patch,darkvalley_tasks,windows,actor_params,vergas_watch,vergas_krujka,linker
[16:56:32.050]. #DBG: neytral load complete 0
[16:56:32.103/ 54.7]. #DBG: safe_net_spawn end
[16:56:32.123/+14.319/ 20.2]. #XRAY: phase time: 74 ms, user time: 46.9 ms, kernel time: 15.6 ms
[16:56:32.123/+14.319/0.039]. #XRAY: phase cmem: 2393.176 M
[16:56:32.123/+14.319/0.023]. #XRAY: Next phase begins: #after actor_binder:net_spawn
[16:56:32.125/+14.321/1.946]. #XRAY: #LEAK: lua stack top raised to 4
[16:56:32.125/+14.321/0.035]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object single_player, work_time = 1387.1 ms, position = { -151.2, 0.7, -187.6 }
[16:56:32.126/+14.322/1.116]. #XRAY: #PERF_WARN: class CActor *::net_Spawn for object single_player elapsed time = 1431.2 ms
[16:56:32.126/+14.322/0.235]. #XRAY: compiling shader deffer_model_bump-hq_0
[16:56:32.134/+14.330/7.248]. #XRAY: compiling shader deffer_model_bump_0
[16:56:32.141/+14.337/7.102]. #XRAY: compiling shader shadow_direct_model_0
[16:56:32.146/+14.342/5.561]. #XRAY: #PERF_WARN: FHierrarhyVisual load from equipments\item_rukzak elapsed time = 20.0 ms
[16:56:32.147/+14.343/0.558]. #XRAY: compiling shader deffer_model_flat_0
[16:56:32.154]. #DBG: bag_on_belt1821 changed owner to 0
[16:56:32.155/+14.351/7.986]. #XRAY: compiling shader deffer_model_flat_1
[16:56:32.162/+14.358/7.006]. #XRAY: compiling shader shadow_direct_model_1
[16:56:32.168/+14.364/6.364]. #XRAY: compiling shader accum_emissive
[16:56:32.171/+14.367/2.818]. #XRAY: compiling shader model_def_lplanes_0
[16:56:32.178/+14.374/6.723]. #XRAY: #PERF_WARN: FHierrarhyVisual load from equipments\light_night elapsed time = 23.0 ms
[16:56:32.179/+14.375/1.518]. #XRAY: compiling shader accum_emissivel
[16:56:32.243/+14.439/ 63.9]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_escort_bandit2_dead elapsed time = 59.1 ms
[16:56:32.278/+14.474/ 35.1]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_escort_bandit1_dead elapsed time = 34.7 ms
[16:56:32.280/+14.476/1.645]. #XRAY: compiling shader deffer_model_bump-hq_1
[16:56:32.288/+14.484/8.051]. #XRAY: compiling shader deffer_model_bump_1
[16:56:32.296/+14.492/7.943]. #XRAY: #PERF_WARN: FHierrarhyVisual load from weapons\grenade\wpn_rgd_5_hud elapsed time = 16.8 ms
[16:56:32.303/+14.499/7.337]. #XRAY: compiling shader shadow_direct_model_aref_0
[16:56:32.315/+14.511/ 11.7]. #XRAY: compiling shader hud_scopes
[16:56:32.318/+14.514/2.829]. #XRAY: compiling shader hud_scopes
[16:56:32.321/+14.517/3.727]. #XRAY: #DBG: 'wpn_binoc3693' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.321/+14.517/0.146]. #XRAY: compiling shader hud_scopes
[16:56:32.322/+14.518/0.136]. #XRAY: compiling shader hud_scopes
[16:56:32.404/+14.600/ 82.2]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object gar_stalker_0007, work_time = 53.9 ms, position = { -82.7, -1.6, 49.0 }
[16:56:32.410/+14.606/5.978]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object gar_stalker_0007 elapsed time = 87.7 ms
[16:56:32.416/+14.612/6.256]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 8.4 MiB
[16:56:32.424/+14.620/7.419]. #XRAY: #DBG: 'wpn_akm3812' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.469/+14.665/ 45.3]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_restrictor', '') for 'gar_stalker_0008'
[16:56:32.471/2.032]. #PERF: update_anoms_cache() #1
[16:56:32.473/2.504]. #PERF: update_anoms_cache() #2, count = 2520
[16:56:32.474/0.693]. #PERF: update_anoms_cache() #3
[16:56:32.475/+14.671/0.799]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object gar_stalker_0008, work_time = 24.6 ms, position = { 34.3, 6.2, -19.4 }
[16:56:32.483/+14.679/7.842]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object gar_stalker_0008 elapsed time = 58.8 ms
[16:56:32.492/+14.688/8.799]. #XRAY: #DBG: 'wpn_ak473946' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.501/+14.697/9.218]. #XRAY: #DBG: 'wpn_pm3947' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.526/+14.722/ 25.0]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_nedostroyka_restrictor', '') for 'gar_stalker_0009'
[16:56:32.529/+14.725/3.148]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object gar_stalker_0009, work_time = 23.9 ms, position = { 39.9, 9.8, -40.9 }
[16:56:32.529/+14.725/0.157]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object gar_stalker_0009 elapsed time = 27.9 ms
[16:56:32.536/+14.732/6.730]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 18.3 MiB
[16:56:32.539/+14.735/3.114]. #XRAY: #DBG: 'wpn_ak743957' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.539/+14.735/0.500]. #XRAY: #DBG: 'wpn_pm3958' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.606/+14.802/ 66.8]. #XRAY: compiling shader deffer_model_bump_d-hq_0
[16:56:32.615/+14.811/8.604]. #XRAY: compiling shader deffer_model_bump_d-hq_1
[16:56:32.625/+14.821/ 10.5]. #XRAY: compiling shader model_env_lq
[16:56:32.628/+14.824/2.872]. #XRAY: compiling shader model_env_lq_1
[16:56:32.639/+14.835/ 10.6]. #XRAY: compiling shader model_env_lq_0
[16:56:32.651/+14.847/ 12.0]. #XRAY: compiling shader deffer_model_bump_d-hq_2
[16:56:32.672/+14.868/ 21.6]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_main_side_restrictor', '') for 'val_stalker_fraer'
[16:56:32.676/+14.872/3.788]. #XRAY: #DBG: 'wpn_ak745265' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.677/+14.873/0.499]. #XRAY: #DBG: 'wpn_pm5266' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.724/+14.920/ 47.7]. #XRAY: #DBG: 'wpn_ak745341' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.780/+14.976/ 55.9]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object val_sacrifice_victim, work_time = 25.3 ms, position = { 13.4, 0.5, 13.5 }
[16:56:32.788/+14.984/7.948]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_sacrifice_victim elapsed time = 63.5 ms
[16:56:32.806/+15.002/ 17.5]. #XRAY: #DBG: 'wpn_bm165348' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.831/+15.027/ 25.4]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object val_sacrifice_guard2, work_time = 23.3 ms, position = { 14.8, 0.3, 16.6 }
[16:56:32.831/+15.027/0.189]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_sacrifice_guard2 elapsed time = 25.2 ms
[16:56:32.837/+15.033/5.711]. #XRAY: #DBG: 'wpn_toz345355' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.889/+15.085/ 51.9]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object val_vlad, work_time = 26.3 ms, position = { 96.4, 4.2, -13.3 }
[16:56:32.889/+15.085/0.136]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_vlad elapsed time = 51.2 ms
[16:56:32.897/+15.093/7.558]. #XRAY: #DBG: 'wpn_sig2205364' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.931/+15.127/ 34.7]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_sos_dead1 elapsed time = 34.2 ms
[16:56:32.939/+15.135/8.111]. #XRAY: CScriptGameObject::add_restrictions('val_watchtower2_restr', '') for 'val_watchtower2_bandit_guard'
[16:56:32.942/+15.138/2.887]. #XRAY: #DBG: 'wpn_ak745382' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.943/+15.139/0.491]. #XRAY: #DBG: 'wpn_pm5383' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.950/+15.146/6.853]. #XRAY: CScriptGameObject::add_restrictions('val_watchtower3_restr', '') for 'val_watchtower3_bandit_guard'
[16:56:32.953/+15.149/3.339]. #XRAY: #DBG: 'wpn_ak745395' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.953/+15.149/0.418]. #XRAY: #DBG: 'wpn_pm5396' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.961/+15.157/7.340]. #XRAY: CScriptGameObject::add_restrictions('val_watchtower4_restr', '') for 'val_watchtower4_bandit_guard'
[16:56:32.963/+15.159/2.677]. #XRAY: #DBG: 'wpn_ak745407' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.964/+15.160/0.494]. #XRAY: #DBG: 'wpn_pm5408' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.976/+15.172/ 12.0]. #XRAY: CScriptGameObject::add_restrictions('val_watchtower5_restr', '') for 'val_watchtower5_bandit_guard'
[16:56:32.979/+15.175/2.877]. #XRAY: #DBG: 'wpn_ak745421' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:32.979/+15.175/0.487]. #XRAY: #DBG: 'wpn_pm5422' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:32.981/+15.177/1.502]. #XRAY: compiling shader model_def_lq
[16:56:32.984/+15.180/2.826]. #XRAY: compiling shader model_def_lq_1
[16:56:32.991/+15.187/7.134]. #XRAY: compiling shader model_def_lq_0
[16:56:32.997/+15.193/6.520]. #XRAY: #PERF_WARN: FHierrarhyVisual load from physics\vehicles\mi24\veh_mi24_u_01 elapsed time = 17.5 ms
[16:56:32.997/+15.193/0.029]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 26.5 MiB
[16:56:33.071/+15.267/ 73.4]. #XRAY: #PERF_WARN: class CGameObject::net_Spawn for object val_lager_bandits_main_side_restrictor elapsed time = 52.7 ms
[16:56:33.211/+15.407/140.6]. #XRAY: #PERF_WARN: class CGameObject::net_Spawn for object val_lager_bandits_restrictor elapsed time = 133.9 ms
[16:56:33.222/+15.418/ 10.4]. #XRAY: CScriptGameObject::add_restrictions('', 'val_lager_bandits_restrictor') for 'val_escort_captive'
[16:56:33.231/+15.427/9.662]. #XRAY: CScriptGameObject::add_restrictions('', 'val_lager_bandits_restrictor') for 'val_escort_guard2'
[16:56:33.234/+15.430/3.185]. #XRAY: #DBG: 'wpn_ak745643' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.241/+15.437/6.528]. #XRAY: CScriptGameObject::add_restrictions('', 'val_lager_bandits_restrictor') for 'val_escort_guard1'
[16:56:33.244/+15.440/2.593]. #XRAY: #DBG: 'wpn_toz345649' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.254/+15.450/ 10.2]. #XRAY: CScriptGameObject::add_restrictions('', 'val_lager_bandits_restrictor') for 'val_escort_nap1'
[16:56:33.267/+15.463/ 12.9]. #XRAY: #DBG: 'wpn_ak74m5656' CWeapon::reload, range_mod = 1.200, fov_mod = 0.300
[16:56:33.267/+15.463/0.522]. #XRAY: #DBG: 'wpn_pm5657' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.285/+15.481/ 17.7]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object mongol elapsed time = 17.4 ms
[16:56:33.285/+15.481/0.387]. #XRAY: #DBG: 'wpn_pm5666' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.286/+15.482/0.370]. #XRAY: #DBG: 'wpn_toz345667' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.292/+15.488/5.787]. #XRAY: #DBG: 'wpn_pb5675' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.292/+15.488/0.400]. #XRAY: #DBG: 'wpn_pm5676' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.298/+15.494/6.520]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_main_side_restrictor', '') for 'val_lager_bandit19'
[16:56:33.301/+15.497/3.056]. #XRAY: #DBG: 'wpn_ak745686' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.303/+15.499/1.577]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 34.9 MiB
[16:56:33.307/+15.503/3.715]. #XRAY: #DBG: 'wpn_knife5687' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.313/+15.509/6.533]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_restrictor', '') for 'val_lager_bandit15'
[16:56:33.316/+15.512/2.669]. #XRAY: #DBG: 'wpn_ak745694' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.324/+15.520/8.065]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_borov_restrictor', '') for 'val_lager_borov'
[16:56:33.335/+15.531/ 11.1]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_lager_borov elapsed time = 18.8 ms
[16:56:33.342/+15.538/6.711]. #XRAY: #DBG: 'wpn_beretta5710' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.347/+15.543/5.369]. #XRAY: #DBG: 'wpn_colt19115711' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.361/+15.557/ 13.9]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_main_building_restrictor', '') for 'val_lager_bodyguard3'
[16:56:33.372/+15.568/ 11.0]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_lager_bodyguard3 elapsed time = 24.5 ms
[16:56:33.381/+15.577/9.055]. #XRAY: #DBG: 'wpn_aks475719' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.382/+15.578/0.489]. #XRAY: #DBG: 'wpn_pm5720' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.389/+15.585/7.075]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_main_building_restrictor', '') for 'val_lager_bodyguard2'
[16:56:33.392/+15.588/2.990]. #XRAY: #DBG: 'wpn_aks475729' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.392/+15.588/0.442]. #XRAY: #DBG: 'wpn_pm5730' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.399/+15.595/6.667]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_main_building_restrictor', '') for 'val_lager_bodyguard1'
[16:56:33.402/+15.598/2.890]. #XRAY: #DBG: 'wpn_aks475738' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.402/+15.598/0.461]. #XRAY: #DBG: 'wpn_pm5739' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.416/+15.612/ 13.5]. #XRAY: compiling shader deffer_model_flat_d_0
[16:56:33.429/+15.625/ 13.4]. #XRAY: compiling shader deffer_model_flat_d_1
[16:56:33.520/+15.716/ 90.8]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_restrictor', '') for 'val_bandit_respawn_15920'
[16:56:33.522/+15.718/2.731]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object val_bandit_respawn_15920, work_time = 26.7 ms, position = { -33.9, 1.2, -13.0 }
[16:56:33.523/+15.719/0.180]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object val_bandit_respawn_15920 elapsed time = 52.5 ms
[16:56:33.536/+15.732/ 13.2]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_restrictor', '') for 'val_bandit_respawn_16112'
[16:56:33.538/+15.734/2.606]. #XRAY: #DBG: 'wpn_pm5948' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.548/+15.744/9.597]. #XRAY: compiling shader shadow_direct_model_aref_2
[16:56:33.557/+15.753/8.792]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 43.4 MiB
[16:56:33.619/+15.815/ 62.5]. #XRAY: #PERF: loading 15000 visual/mesh...
[16:56:33.621/+15.817/1.931]. #XRAY: #DBG: 'wpn_pm6111' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.622/+15.818/0.631]. #XRAY: #DBG: 'wpn_knife6118' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.640/+15.836/ 18.3]. #XRAY: CScriptGameObject::add_restrictions('', 'val_lager_bandits_restrictor') for 'val_escort_guard3'
[16:56:33.650/+15.846/ 10.0]. #XRAY: #DBG: 'wpn_ak74u6202' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:33.673/+15.869/ 22.9]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 51.5 MiB
[16:56:33.703/+15.899/ 29.8]. #XRAY: compiling shader model_distort4glass_0
[16:56:33.842/+16.038/139.1]. #XRAY: CScriptGameObject::add_restrictions('val_rob_zone', '') for 'red_forest_stalker_03'
[16:56:33.855/+16.051/ 13.5]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object red_forest_stalker_03, work_time = 35.9 ms, position = { -133.9, 1.1, -520.5 }
[16:56:33.856/+16.052/0.265]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object red_forest_stalker_03 elapsed time = 66.4 ms
[16:56:33.863/+16.059/7.309]. #XRAY: #DBG: 'wpn_fort17999' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:33.867/+16.063/3.542]. #XRAY: compiling shader shadow_direct_model_aref_1
[16:56:33.881/+16.077/ 14.5]. #XRAY: #DBG: 'wpn_g3618000' CWeapon::reload, range_mod = 1.200, fov_mod = 0.300
[16:56:33.988/+16.184/106.7]. #XRAY: CScriptGameObject::add_restrictions('val_rob_zone', '') for 'mil_stalker_respawn_220053'
[16:56:33.991/+16.187/3.514]. #XRAY: #PERF_WARN: CScriptBinderObjectWrapper::net_Spawn for object mil_stalker_respawn_220053, work_time = 24.0 ms, position = { -137.6, 1.0, -490.0 }
[16:56:33.991/+16.187/0.187]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object mil_stalker_respawn_220053 elapsed time = 48.4 ms
[16:56:33.993/+16.189/1.098]. #XRAY: #DBG: 'wpn_ak7420062' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.001/+16.197/8.773]. #XRAY: #DBG: 'wpn_walther20063' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.012/+16.208/ 10.3]. #XRAY: CScriptGameObject::add_restrictions('val_rob_zone', '') for 'gar_stalker_respawn_220496'
[16:56:34.015/+16.211/3.029]. #XRAY: #DBG: 'wpn_ak7420503' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.015/+16.211/0.489]. #XRAY: #DBG: 'wpn_pm20504' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.022/+16.218/6.764]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_nedostroyka_restrictor', '') for 'ds_bandit_respawn_320514'
[16:56:34.025/+16.221/3.046]. #XRAY: #DBG: 'wpn_ak7420523' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.025/+16.221/0.356]. #XRAY: #DBG: 'wpn_knife20524' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.026/+16.222/0.390]. #XRAY: #DBG: 'wpn_pm20525' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.038/+16.234/ 12.0]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 59.6 MiB
[16:56:34.080/+16.276/ 42.4]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_nedostroyka_restrictor', '') for 'cit_bandit_respawn_120736'
[16:56:34.083/+16.279/2.787]. #XRAY: #PERF_WARN: class CEntityAlive *::net_Spawn for object cit_bandit_respawn_120736 elapsed time = 43.8 ms
[16:56:34.084/+16.280/1.216]. #XRAY: #DBG: 'wpn_ak7420747' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.085/+16.281/0.478]. #XRAY: #DBG: 'wpn_pm20748' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.092/+16.288/7.151]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_restrictor', '') for 'ros_bandit_respawn_321041'
[16:56:34.095/+16.291/3.211]. #XRAY: #DBG: 'wpn_ak7421049' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.095/+16.291/0.456]. #XRAY: #DBG: 'wpn_pm21050' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.102/+16.298/6.153]. #XRAY: compiling shader model_distort_2
[16:56:34.114/+16.310/ 12.4]. #XRAY: #DBG: 'wpn_knife21508' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.133/+16.329/ 18.7]. #XRAY: CScriptGameObject::add_restrictions('val_lager_bandits_nedostroyka_restrictor', '') for 'val_bandit_respawn_333035'
[16:56:34.142]. #DBG: belt_2_art23299 changed owner to 0
[16:56:34.146/+16.342/ 13.0]. #XRAY: compiling shader deffer_base_aref_bump-hq
[16:56:34.151/+16.347/5.293]. #XRAY: #DBG: 'wpn_knife65535' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.152/+16.348/0.813]. #XRAY: #DBG: 'wpn_knife65535' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.152/+16.348/0.444]. #XRAY: #DBG: 'wpn_knife65535' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.153/+16.349/0.724]. #XRAY: #DBG: 'wpn_ak7433029' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.154/+16.350/0.735]. #XRAY: #DBG: 'wpn_pm33043' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.158/+16.354/4.470]. #XRAY: compiling shader model_distort_0
[16:56:34.168/+16.364/9.485]. #XRAY: #PERF_MEM: total loaded in FHierrarhyVisual::Load = 68.2 MiB
[16:56:34.169/+16.365/1.644]. #XRAY: #DBG: 'wpn_knife_m137133' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.183]. #DBG: flask_army_on_belt38913 changed owner to 0
[16:56:34.183/+16.379/ 14.3]. #XRAY: #DBG: 'wpn_pm39703' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
[16:56:34.191/+16.387/7.490]. #XRAY: #DBG: 'wpn_ak74u_old43010' CWeapon::reload, range_mod = 1.000, fov_mod = 0.700
[16:56:34.196/+16.392/5.316]. #XRAY: #DBG: 'wpn_bm16_full53509' CWeapon::reload, range_mod = 1.000, fov_mod = 1.500
[16:56:34.199/+16.395/2.604]. #XRAY: #DBG: CActor first UpdateCL call
[16:56:34.199/+16.395/0.023]. #XRAY: @---------- CApplication::LoadEnd --------------
[16:56:34.199/+16.395/0.023]. #XRAY: phase time: 2076 ms
[16:56:34.219/+16.415/ 20.0]. #XRAY: phase cmem: 2475.531 M
[16:56:34.272/+16.468/ 53.1]. #XRAY: #PERF_WARN: after func = 0x00566200 (rp_Frame + 0x0), obj = 0x308BBC98 (unknown @ 0x308BBC98 + 0x8) frame elapsed = 3773 ms (+3773), ticks1 = 3782, ticks2 = 3782
[16:56:34.385/113.1]. #ACTOR_BINDER: game time = 15:46:07, binder_ticks = 0, binder_loops = 1
[16:56:34.385/main ($328c)(12940)]. #DBG: ProcessCommands called from $2E50F5C8
[16:56:34.388]. #DBG: imitation_opening_inventory()
[16:56:34.388]. #DBG: razgruzka.open_inv_menu()
[16:56:34.423]. #DBG: client object single_player pInstance updated from $B3FFF0E2 to $4E8C1DB0, ref = $30520050
[16:56:34.423/ 38.2/12940]. #PERF_WARN(TRootRegistry.Update): so(1) = 54, ap = 693, la = 34754, um = 0 mcs add = 0, chk = 20760 upd = 17, heavy = 20760, loop = $3 [36 ms]
added = 0, reg_events = 0, destr_events = 0
addr range = [$1674E000 .. $4F079000]
[16:56:34.426/+16.622/2.582]. #XRAY: #PERF: game_events->queue.size() returned 183
CTexture.SetName(act\act_arm_perchatka008), old = act\act_arm_perchatka_cs
after load texture name = act\act_arm_perchatka008
CTexture.SetName(act\act_arm_perchatka008_bump#), old = act\act_arm_perchatka_cs_bump#
after load texture name = act\act_arm_perchatka008_bump#
CTexture.SetName(act\act_arm_perchatka008_bump), old = act\act_arm_perchatka_cs_bump
after load texture name = act\act_arm_perchatka008_bump
CTexture.SetName(act\act_arm_008), old = act\act_arm_css
after load texture name = act\act_arm_008
CTexture.SetName(act\act_arm_008_bump), old = act\act_arm_css_bump
after load texture name = act\act_arm_008_bump
CTexture.SetName(act\act_arm_008_bump#), old = act\act_arm_css_bump#
after load texture name = act\act_arm_008_bump#
CTexture.SetName(ui\ui_hud007), old = ui\ui_hud_mm
after load texture name = ui\ui_hud007
[16:56:34.447]. #DBG: registered artefact af_rusty_kristall_dyn2d:96 in arc_arts.inv_arts, condition = 0.960 <---------
[16:56:34.447]. #DBG: registered artefact af_ameba_slime_dyn3d:96 in arc_arts.inv_arts, condition = 0.970 <---------
[16:56:34.447]. #DBG: registered artefact af_blood_dyn3d:89 in arc_arts.inv_arts, condition = 0.892 <---------
[16:56:34.447]. #DBG: parsing arts for 22034 '_,_,_,_,_,_,_,_'
[16:56:34.455]. #DBG: registered artefact af_electra_flash_dyn2d:98 in arc_arts.belt_arts, condition = 0.987 <---------
max_belt updated to 1
CTexture.SetName(ui\amk_ui_base_textur2), old = ui\amk_ui_base_textur0
after load texture name = ui\amk_ui_base_textur2
[16:56:34.461]. #DBG: transfer_items, bind_stalker.taked_items size = 0
[16:56:34.581]. #SUCCESS: inv:hide_content(0)
[16:56:34.584/157.7]. #PERF_WARN: frame 37 elapsed mid_update = 3.0 ms, end update = 199.0 ms, ema_start = 0.0 ms, ema_end = 0.0, ticks= 1
[ogse_signals]: time limit functionality paused: 10000 ms
[16:56:34.584/+16.780/0.499]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_buzz_weak6863,amk_zone_gravi_zone_weak6562') for 'gar_stalker_0007'
For object gar_stalker_0007 were added restrictions listed below -
[16:56:34.585/+16.781/0.387]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_stalker_fraer'
For object val_stalker_fraer were added restrictions listed below -
[16:56:34.585/+16.781/0.268]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_mincer_average4328') for 'val_sacrifice_tunnel_bandit'
For object val_sacrifice_tunnel_bandit were added restrictions listed below -
[16:56:34.585/+16.781/0.464]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_witches_galantine_average4343,amk_zone_mosquito_bald_strong4335,amk_zone_mosquito_bald_average4385,amk_zone_mosquito_bald_strong6807') for 'val_vlad'
For object val_vlad were added restrictions listed below -
[16:56:34.586/+16.782/0.270]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_buzz_weak6769,amk_zone_zharka_static_strong6766,zone_ice4967') for 'val_watchtower2_bandit_guard'
For object val_watchtower2_bandit_guard were added restrictions listed below -
[16:56:34.586/+16.782/0.315]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_watchtower4_bandit_guard'
For object val_watchtower4_bandit_guard were added restrictions listed below -
[16:56:34.586/+16.782/0.292]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_mincer_strong4336,amk_zone_mincer_weak4877,amk_zone_mincer_average4261,amk_zone_buzz_weak12728,amk_zone_sakbuzz_average14913') for 'val_watchtower5_bandit_guard'
For object val_watchtower5_bandit_guard were added restrictions listed below -
[16:56:34.586/+16.782/0.336]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_weak4924') for 'val_escort_captive'
For object val_escort_captive were added restrictions listed below -
[16:56:34.587/+16.783/0.350]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_mincer_average4261,amk_zone_witches_galantine_average4316,amk_zone_zharka_static_strong4923') for 'val_escort_guard2'
For object val_escort_guard2 were added restrictions listed below -
[16:56:34.587/+16.783/0.290]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_mincer_average4261,amk_zone_sakbuzz_weak4345,amk_zone_witches_galantine_average4316') for 'val_escort_guard1'
For object val_escort_guard1 were added restrictions listed below -
[16:56:34.588/+16.784/0.444]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_lager_borov'
For object val_lager_borov were added restrictions listed below -
[16:56:34.588/+16.784/0.218]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_lager_bodyguard3'
For object val_lager_bodyguard3 were added restrictions listed below -
[16:56:34.588/+16.784/0.247]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_lager_bodyguard2'
For object val_lager_bodyguard2 were added restrictions listed below -
[16:56:34.588/+16.784/0.215]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_zharka_static_strong4923') for 'val_lager_bodyguard1'
For object val_lager_bodyguard1 were added restrictions listed below -
[16:56:34.589/+16.785/0.358]. #XRAY: CScriptGameObject::add_restrictions('', 'amk_zone_buzz_weak12728,amk_zone_sakbuzz_weak4345') for 'val_escort_guard3'
For object val_escort_guard3 were added restrictions listed below -
[16:56:34.589/+16.785/0.401]. #XRAY: #PERF: binder::update for object single_player eats time = 203 ms
[16:56:34.709/+16.905/119.6]. #XRAY: phase time: 2585 ms, user time: 2250.0 ms, kernel time: 187.5 ms
[16:56:34.709/+16.905/0.040]. #XRAY: phase cmem: 2616.375 M
[16:56:34.709/+16.905/0.025]. #XRAY: Next phase begins: #actor_binder:early_init
[16:56:34.710]. #DBG: total flame anomalies in game = 150, in_level = 24
[ s_fixes ]: fixes_pack is empty
[16:56:34.843/134.5]. #PERF: update_anoms_cache() #1
[16:56:34.850/6.922]. #PERF: update_anoms_cache() #2, count = 2520
[16:56:34.851/0.672]. #PERF: update_anoms_cache() #3
[16:56:34.913/ 62.6]. #DBG:campmgr.init_level_camps() called
objects_active.length = 606
objects_sleeping.length = 578
[16:56:34.943/ 29.2]. #PERF: Direct VMScan regions checked 37000, g_scanner.scan_loop = 24, bad_ptrs = 0
[16:56:34.961/ 18.2/12940]. #PERF_WARN(TRootRegistry.Update): so(0) = 2056, ap = 513, la = 37834, um = 6914 mcs add = 42, chk = 21943 upd = 1183, heavy = 21985, loop = $6 [47 ms]
added = 42, reg_events = 42, destr_events = 0
addr range = [$F909000 .. $B4010000]
stack traceback:
... 3.8.2_release\gamedata\scripts\alpet\campmgr.script:240: in function 'init_level_camps'
...release\gamedata\scripts\binders\bind_stalker.script:668: in function 'early_init'
...release\gamedata\scripts\binders\bind_stalker.script:992: in function 'process_binder_update'
...release\gamedata\scripts\binders\bind_stalker.script:931: in function <...release\gamedata\scripts\binders\bind_stalker.script:929>
#WARN: load_mask changed to $7F
[16:56:34.992/+17.188/ 31.3]. #XRAY: #PERF: binder::update for object single_player eats time = 78 ms
[16:56:35.053/+17.249/ 60.4]. #DBG: actor_binder:late_init(), db.actor = $419B5E90:game_object # 0 single_player class CActor *
[16:56:35.053/main ($328c)(12940)]. #DBG: ProcessCommands called from $2E50F5C8
[16:56:35.054]. #DBG: updating regular task list...
[16:56:35.054/1.557]. #DBG: registering bind_stalker regular tasks...
[16:56:35.054]. #DBG: updated regular task binder_update_2
[16:56:35.054]. #DBG: updated regular task binder_update_4
[16:56:35.054]. #DBG: updated regular task binder_update_7
[16:56:35.054]. #DBG: updated regular task binder_update_8
[16:56:35.054]. #DBG: updated regular task binder_update_10
[16:56:35.057/+17.253/2.684]. #PERF: actor_binder init_stage = 12, context = ?
[16:56:35.057/+17.253/0.084]. #PERF: actor_binder init_stage = 13, context = mod_call('first_run')
[16:56:35.057/+17.253/0.099]. #PERF: actor_binder init_stage = 14, context = gps_habar.on_game_loading
[16:56:35.057/+17.253/0.163]. #PERF: actor_binder init_stage = 15, context = gps_habar.on_game_loading#2
[16:56:35.057/+17.253/0.082]. #PERF: gps_habar.load_items start
[16:56:35.060/+17.256/3.069]. #PERF: gps_habar.load_items cnt_box = 540
[16:56:35.090/ 29.3]. #PERF: Direct VMScan regions checked 38000, g_scanner.scan_loop = 25, bad_ptrs = 0
[16:56:35.114/+17.310/ 24.3]. #PERF: gps_habar.load_items owner_list size = 1016, added items = 6223
[16:56:35.126/+17.322/ 12.5]. #PERF: gps_habar.load_items stalkers = 838
[16:56:35.130/+17.326/3.879]. #PERF: gps_habar.load_items processed 5808 objects
[16:56:35.130/+17.326/0.052]. #PERF: actor_binder init_stage = 16, context = mod_call('check_spawn')
[16:56:35.130/+17.326/0.113]. #PERF: amk_mod.check_spawn begin
[16:56:35.149]. #DBG: loading anomalies for level l04_darkvalley
[16:56:35.157]. #DBG: parsed 791 lines
[16:56:35.157/ 26.9]. #PERF: update_anoms_cache() #1
[16:56:35.160/2.381]. #PERF: update_anoms_cache() #2, count = 2520
[16:56:35.160/0.686]. #PERF: update_anoms_cache() #3
[16:56:35.243/+17.439/ 83.0]. #PERF: spawn_unspawned_respawners()
[16:56:35.260/+17.456/ 16.7]. #PERF: updating LC
[16:56:35.260/+17.456/0.165]. #PERF: updating npc tables
[16:56:35.434/+17.630/174.1]. #PERF: updating weahter
[16:56:35.435/+17.631/0.286]. #PERF: amk_mod.check_spawn end
[16:56:35.435/+17.631/0.055]. #PERF_WARN: amk.mod_call('check_spawn') eats time 304.0 ms
[16:56:35.435/+17.631/0.344]. #PERF: sak.add_tail begin
CTexture.SetName(trees\3rees_elka), old = trees\trees_elka
after load texture name = trees\3rees_elka
CTexture.SetName(trees\3rees_vetkabig), old = trees\trees_vetkabig
after load texture name = trees\3rees_vetkabig
CTexture.SetName(trees\3rees_vetkagreen1), old = trees\trees_vetkagreen1
after load texture name = trees\3rees_vetkagreen1
CTexture.SetName(trees\3rees_vetkagreen3), old = trees\trees_vetkagreen3
after load texture name = trees\3rees_vetkagreen3
CTexture.SetName(trees\3rees_vetkagreen5), old = trees\trees_vetkagreen5
after load texture name = trees\3rees_vetkagreen5
CTexture.SetName(trees\3rees_vetkasux2), old = trees\trees_vetkasux2
after load texture name = trees\3rees_vetkasux2
CTexture.SetName(ston\2ton_rocks1), old = ston\ston_rocks1
after load texture name = ston\2ton_rocks1
static rect = { 3.0, -800.0, 135.4, -728.0 }
[16:56:35.656/+17.852/221.1]. #PERF: sak.add_tail end
#ACLOG#WPNHIDE: [allcore_hide.onLoad(): vars, stay_while_use = 1, medkit_hide_wpn = 1, eat_in_mask = 1]
[16:56:35.679/+17.875/ 23.2]. #XRAY: phase time: 970 ms, user time: 859.4 ms, kernel time: 46.9 ms
[16:56:35.679/+17.875/0.041]. #XRAY: phase cmem: 2728.320 M
[16:56:35.680/+17.876/0.023]. #XRAY: Next phase begins: #late_init_all
[16:56:35.719/ 39.9/12940]. #PERF_WARN(TRootRegistry.Update): so(0) = 1970, ap = 515, la = 36083, um = 482 mcs add = 1, chk = 21990 upd = 1, heavy = 21990, loop = $C [39 ms]
added = 1, reg_events = 1, destr_events = 2
addr range = [$F909000 .. $B3FFF0E2]
stack traceback:
... 3.8.2_release\gamedata\scripts\alpet\modmgmt.script:322: in function 'late_init_all'
...release\gamedata\scripts\binders\bind_stalker.script:682: in function <...release\gamedata\scripts\binders\bind_stalker.script:672>
[16:56:35.720]. #DBG: trying invoke late_init for modules schedule,misc,smart_terrain,amk_mod,vergas_lib,balance,campmgr,xray_vars,black_tip,const,registry,nvd_params,savemgmt,bind_nlc,wpn_params,razgruzka,sak_repair_dialog,sak_dialog,params,nlc_container,xr_meet,task_smart_upd,xr_statistic,hud_params,watcher_act,sak_inventory,kanistra_flame,bind_stalker,flamethrower,sak,class_info,arc_arts,profiles,lightman,xray_patch,darkvalley_tasks,windows,actor_params,vergas_watch,vergas_krujka,linker
[16:56:35.731]. #DBG: hud_params.late_init performing...
[16:56:35.731/ 11.9]. #PERF: start verify ptrs...
[16:56:35.750/ 18.8]. #PERF: InvokeRegistry called 10000 counts
[16:56:35.773/ 22.6]. #PERF: InvokeRegistry called 20000 counts
[16:56:35.787/ 13.9]. #PERF: end verify ptrs. SE_objects = 20782, client objects = 1208
replacing texture ui\accum_leds\cl_100 to ui\accum_leds\cl_080
CTexture.SetName(ui\accum_leds\cl_080), old = ui\accum_leds\cl_100
after load texture name = ui\accum_leds\cl_080
#DBG: yep = false
[16:56:35.793]. #DBG: loaded saves count = 1
extracted key for quick_save = kF6
extracted key for night_vision = kN
[16:56:35.798]. #DBG: BinThisCall this = $1BA7F4A8, func = $1B3EA300, { eax = $0, ebx = $0, edx = $0, edi = $0, esi = $0, ebp = $1932C4 }, pushed = 1: $00192DC0
[16:56:35.899]. #DBG: updating all keyboard callbacks...
[16:56:35.899]. #WARN: 0 keyboard callbacks removed via command CLEAR_KCB_LIST
[16:56:35.899]. #DBG: trying invoke init_kcb for modules schedule,misc,smart_terrain,amk_mod,vergas_lib,balance,campmgr,xray_vars,black_tip,const,registry,nvd_params,savemgmt,bind_nlc,wpn_params,razgruzka,sak_repair_dialog,sak_dialog,params,nlc_container,xr_meet,task_smart_upd,xr_statistic,hud_params,watcher_act,sak_inventory,kanistra_flame,bind_stalker,flamethrower,sak,class_info,arc_arts,profiles,lightman,xray_patch,darkvalley_tasks,windows,actor_params,vergas_watch,vergas_krujka,linker
extracted key for night_vision = kN
set NVD key for callback N
[16:56:35.903/+18.099/116.2]. #XRAY: Config-file [e:\nlc 3.8.2_release\userdata_nlc7\user.ltx] saved successfully
[16:56:35.926/+18.122/ 22.7]. #XRAY: phase time: 245 ms, user time: 125.0 ms, kernel time: 31.3 ms
[16:56:35.926/+18.122/0.038]. #XRAY: phase cmem: 2728.352 M
[16:56:35.926/+18.122/0.025]. #XRAY: Next phase begins: #actor_binder:late_init
#ACLOG|#MAYATNIKS_DBG: [allcore_mayatniks.on_late_init()] loading done. Current phase = 2, count of phase = 55, count of saved mayatniks = 0, count of saved containers = 5
[16:56:35.927/+18.123/1.159]. #XRAY: #PERF: binder::update for object single_player eats time = 874 ms
[16:56:35.927/+18.123/0.201]. #XRAY: #PERF_WARN: after func = 0x00566200 (rp_Frame + 0x0), obj = 0x2BADF7A0 (unknown @ 0x2BADF7A0 + 0x2) frame elapsed = 875 ms (+875), ticks1 = 875, ticks2 = 875
[16:56:36.000/main ($328c)(12940)]. #DBG: ProcessCommands called from $2E50F5C8
[16:56:36.000]. #DBG: executing on_remove ({ 21522 })
[16:56:36.003/+18.199/ 76.5]. #XRAY: deleting script_var var_mask_on_off
[16:56:36.006/+18.202/2.457]. #XRAY: deleting script_var rt1
[16:56:36.006/+18.202/0.040]. #XRAY: deleting script_var gt1
[16:56:36.006/+18.202/0.035]. #XRAY: deleting script_var gt2
[16:56:36.006/+18.202/0.043]. #XRAY: deleting script_var gt3
[16:56:36.006/+18.202/0.030]. #XRAY: deleting script_var gt4
[16:56:36.006/+18.202/0.028]. #XRAY: deleting script_var gt5
[16:56:36.006/+18.202/0.023]. #XRAY: deleting script_var gt6
[16:56:36.007]. #DBG: BinThisCall this = $1BA7F4A8, func = $1B3EA300, { eax = $0, ebx = $0, edx = $0, edi = $0, esi = $0, ebp = $193294 }, pushed = 1: $00192D90
[16:56:36.108/+18.304/102.3]. #XRAY: deleting script_var tm_news
[16:56:36.145/ 36.7]. #DBG_razgruzka: открывается инвентарь TRUE.
[16:56:36.145]. #DBG: razgruzka.open_inventory()
[16:56:36.153]. #DBG: transfer_items, bind_stalker.taked_items size = 0
[16:56:36.184]. #SUCCESS: inv:hide_content(1)
[16:56:36.184]. #DBG: BinThisCall this = $1BA7F4A8, func = $1B3EA300, { eax = $0, ebx = $0, edx = $0, edi = $0, esi = $0, ebp = $1932FC }, pushed = 1: $00192DF8
[16:56:36.285/139.7]. #PERF_WARN: schedule.run_task breaked at r_sun, after 285.0 ms
[16:56:36.285/0.056]. #PERF: task run_tasks elapsed = 279.5, num_calls = 1
[16:56:36.285/+18.481/0.198]. #XRAY: #PERF: binder::update for object single_player eats time = 118 ms
[16:56:36.299]. #DBG: gTexList.List relocated to $B3FC468C
[16:56:36.304]. #DBG: satiety velocity updated from 0.000004 to 0.00011200, satiety health = 0.26203224
[16:56:36.561/+18.757/275.8]. #XRAY: MEMORY USAGE: 2860280 K
[16:56:36.612]. #DBG: executing on_remove ({ 21522 })
[16:56:36.686/+18.882/125.1]. #XRAY: phase time: 760 ms, user time: 265.6 ms, kernel time: 140.6 ms
[16:56:36.686/+18.882/0.043]. #XRAY: phase cmem: 2799.289 M
[16:56:36.686/+18.882/0.024]. #XRAY: Next phase begins: #sak.paused_game binder_ticks = 23
[16:56:36.689]. #DBG: set_light_range id = 1822, range = 15.84, lamp = $BDECE788:CTorch
[16:56:36.690]. #DBG_razgruzka: закрывается инвентарь nil.
active detector: detector_sakbuzz_simple27489 in slot 8
[16:56:36.691]. #DBG: transfer_items, bind_stalker.taked_items size = 15
[16:56:36.727]. #SUCCESS: inv:hide_content(0)
[16:56:36.729/ 43.0]. #PERF_WARN: schedule.run_task breaked at paused_game, after 67.0 ms
[16:56:36.729/0.053]. #PERF: task run_tasks elapsed = 67.3, num_calls = 10
[16:56:36.729/+18.925/0.036]. #XRAY: #PERF: binder::update for object single_player eats time = 67 ms
[16:56:37.732/+19.928/ 1003]. #SUCCESS: --------------------- saved game was complete loaded -------------------------------
active detector: detector_sakbuzz_simple27489 in slot 8
[16:56:37.732/+19.928/0.385]. #XRAY: from
[16:56:37.732/+19.928/0.025]. #XRAY: stack traceback:
[16:56:37.732/+19.928/0.021]. #XRAY: [C]: in function 'create'
[16:56:37.732/+19.928/0.021]. #XRAY: ...nlc 3.8.2_release\gamedata\scripts\alpet\misc.script:156: in function 'spawn_to'
[16:56:37.732/+19.928/0.021]. #XRAY: e:\nlc 3.8.2_release\gamedata\scripts\sak\sak.script:8231: in function 'fix_armor'
[16:56:37.732/+19.928/0.020]. #XRAY: e:\nlc 3.8.2_release\gamedata\scripts\sak\sak.script:8211: in function 'on_load_complete'
[16:56:37.732/+19.928/0.022]. #XRAY: [string "sak.on_load_complete()"]:1: in function 'foo'
[16:56:37.732/+19.928/0.022]. #XRAY: ...3.8.2_release\gamedata\scripts\alpet\schedule.script:86: in function 'func'
[16:56:37.732/+19.928/0.021]. #XRAY: ...release\gamedata\scripts\alpet\task_smart_upd.script:237: in function 'DoRegularTask'
[16:56:37.733/+19.929/0.021]. #XRAY: ...release\gamedata\scripts\alpet\task_smart_upd.script:344: in function <...release\gamedata\scripts\alpet\task_smart_upd.script:304>
[16:56:37.733]. #DBG: SetXrPointer updating destroy_queue from $00000000 to $308BBCCC
[fix_armor]: fake_item wpn_knife23562 spawned
[16:56:37.735/+19.931/2.236]. #XRAY: #DBG: 'wpn_knife23562' CWeapon::reload, range_mod = 1.000, fov_mod = 1.000
trying put wpn_knife_m137133 to on from slots 0
detected unused slot 0
[16:56:37.768/+19.964/ 33.1]. #XRAY: auto-generated bump map: act\act_faces_1_02_bump
[16:56:37.769/+19.965/0.859]. #XRAY: auto-generated bump map: act\act_faces_1_02_bump#
[16:56:38.175/+20.371/406.7]. #XRAY: game render status switched to 'interactive' from '::LoadTitelInt - on st_load_end'
[16:56:38.202/+20.398/ 26.9]. #XRAY: #MEM_USAGE: g_heap[1] usage = 64.002 MiB
[16:56:38.203/+20.399/0.242]. #XRAY: #MEM_USAGE: g_heap[255] usage = 95.718 MiB
[16:56:38.244/+20.440/ 41.5]. #XRAY: #MEM_USAGE: unregistered heap 0x2e490000 found, usage = 88.753 MiB
[16:56:38.321/+20.517/ 76.6]. #XRAY: [win32]: free[ 673684 K], reserved[ 294516 K], committed[ 3226040 K], mapped[ 28134 K], VM direct[0 K]
[16:56:38.321/+20.517/0.046]. #XRAY: [changes]: free[+1510268 K], reserved[ +52624 K], committed[-1562892 K], mapped[ +28134 K]
[16:56:38.346/+20.542/ 25.8]. #XRAY: [ D3D ]: textures[1744438 K], c_base[1 K], c_lmaps [0 K]
[16:56:38.346/+20.542/0.040]. #XRAY: [x-ray]: crt heap[2062184 K], process heap[222003 K], lib heap [104498 K], game lua[94361 K], engine lua[199 K], render[2259 K]
[16:56:38.346/+20.542/0.028]. #XRAY: [x-ray]: economy: strings[12544 K], smem[8199 K]
[16:56:38.347/+20.543/0.776]. #PERF: luaicp dynamic memory usage: allocated = 179.984 MiB, overhead = 13.826 MiB
mm_list[1] cb_alloc = 0.66882 GiB, cb_freed = 0.57793 GiB, op_count = 10.9M
[16:56:38.347/+20.543/0.052]. #XRAY: [total]: [2474.986 M], [external]: [675.444 M]
[16:56:38.347/+20.543/0.108]. #XRAY: phase time: 18056 ms, user time: 453.1 ms, kernel time: 31.3 ms
[16:56:38.347/+20.543/0.025]. #XRAY: phase cmem: 2802.105 M
[16:56:38.347/+20.543/0.030]. #XRAY: Next phase begins: #st_load_end
[16:56:38.348/0.102]. #PERF_WARN: schedule.run_task breaked at pause, after 615.0 ms
[16:56:38.348/0.050]. #PERF: task run_tasks elapsed = 615.7, num_calls = 41
[16:56:38.348/+20.544/0.041]. #XRAY: #PERF: binder::update for object single_player eats time = 615 ms
[16:56:38.348/+20.544/0.170]. #XRAY: #PERF_WARN: after func = 0x00566200 (rp_Frame + 0x0), obj = 0x2BADF7A0 (unknown @ 0x2BADF7A0 + 0x1) frame elapsed = 616 ms (+616), ticks1 = 625, ticks2 = 625
[16:56:38.351/3.302]. #PERF: adjust_spawned_objects start, cp = { -151.2, 0.8, 0.8 }
[16:56:38.354]. #DBG: Game paused changed to 1
[16:56:38.369/ 18.0]. #DBG: on level objects now = 1165
[16:56:38.369/0.053]. #DBG: for processing objects 1165
[16:56:38.380/ 10.6]. #DBG: adjust_spawned_object end, processed = 50, last_id = 3957
[16:56:38.380/0.046]. #PERF: adjust_spawned_objects end
[16:56:38.380/0.146]. #PERF_WARN: schedule.run_task breaked at mayanik_itr_upd, after 648.0 ms
take disabled for wpn_knife23562 due drop event
[16:56:43.061]. #DBG: Game paused changed to 0
npc 5658 hit rastopka "rastopka21807" section = "rastopka"
[16:56:43.103/ 4723]. #PERF: task npc_fire_camps elapsed = 33.8, num_calls = 1
[16:56:43.103/+25.299/0.172]. #XRAY: #PERF: binder::update for object single_player eats time = 56 ms
[16:56:43.172]. #DBG: executing on_remove ({ 23562 })
npc 5658 hit rastopka "rastopka21807" section = "rastopka"
#DBG: rastopka #21807 condition = 0.00000, passed for destroy...
[16:56:45.694]. #DBG: executing on_remove ({ 21807 })
[16:56:45.695]. #DBG: Ok! Rastopka 21807 wasted! flame_id = 2252, marker_id = 6032
spawn timer into $419B5E90:game_object # 0 single_player class CActor *
[16:56:46.989/+29.185/ 3887]. #XRAY: from
[16:56:46.989/+29.185/0.025]. #XRAY: stack traceback:
[16:56:46.989/+29.185/0.021]. #XRAY: [C]: in function 'create'
[16:56:46.989/+29.185/0.023]. #XRAY: ...nlc 3.8.2_release\gamedata\scripts\alpet\misc.script:156: in function 'spawn_to'
[16:56:46.989/+29.185/0.023]. #XRAY: ....2_release\gamedata\scripts\vergas\vergas_lib.script:34: in function 'spawn_item_in_inv'
[16:56:46.989/+29.185/0.021]. #XRAY: ..._release\gamedata\scripts\vergas\vergas_watch.script:564: in function 'substitution_watch'
[16:56:46.989/+29.185/0.022]. #XRAY: ....2_release\gamedata\scripts\vergas\vergas_lib.script:383: in function 'inventory_info'
[16:56:46.990/+29.186/0.025]. #XRAY: ...release\gamedata\scripts\binders\bind_stalker.script:338: in function <...release\gamedata\scripts\binders\bind_stalker.script:324>
trying take spawned 24579 into razgruzka
[16:56:46.995/5.142]. #DBG_razgruzka: открывается инвентарь TRUE.
[16:56:46.995]. #DBG: razgruzka.open_inventory()
[16:56:47.004]. #DBG: transfer_items, bind_stalker.taked_items size = 58
[16:56:47.015]. #SUCCESS: inv:hide_content(1)
[16:56:47.053]. #DBG: executing on_remove ({ 28417 })
[16:56:47.124/129.5]. #PERF: task npc_fire_camps elapsed = 36.6, num_calls = 3
spawn watch into $419B5E90:game_object # 0 single_player class CActor *
[16:56:48.684/+30.880/ 1560]. #XRAY: from
[16:56:48.684/+30.880/0.028]. #XRAY: stack traceback:
[16:56:48.684/+30.880/0.023]. #XRAY: [C]: in function 'create'
[16:56:48.684/+30.880/0.022]. #XRAY: ...nlc 3.8.2_release\gamedata\scripts\alpet\misc.script:156: in function 'spawn_to'
[16:56:48.684/+30.880/0.023]. #XRAY: ....2_release\gamedata\scripts\vergas\vergas_lib.script:34: in function 'spawn_item_in_inv'
[16:56:48.684/+30.880/0.021]. #XRAY: ..._release\gamedata\scripts\vergas\vergas_watch.script:564: in function 'substitution_watch'
[16:56:48.684/+30.880/0.021]. #XRAY: ....2_release\gamedata\scripts\vergas\vergas_lib.script:403: in function 'inventory_info'
[16:56:48.684/+30.880/0.025]. #XRAY: ...release\gamedata\scripts\binders\bind_stalker.script:338: in function <...release\gamedata\scripts\binders\bind_stalker.script:324>
trying take spawned 25686 into razgruzka
[16:56:48.691]. #DBG_razgruzka: закрывается инвентарь nil.
active detector: detector_sakbuzz_simple27489 in slot 8
[16:56:48.692]. #DBG: transfer_items, bind_stalker.taked_items size = 16
[16:56:48.703]. #SUCCESS: inv:hide_content(0)
[16:56:51.206/+33.402/ 2522]. #XRAY: #ERROR: detected bad object (NULL) in inventory m_all[55/87]
[16:56:51.206/+33.402/0.321]. #XRAY: #PERF_WARN: after func = 0x00566200 (rp_Frame + 0x0), obj = 0x2BADF7A0 (unknown @ 0x2BADF7A0 + 0x5) frame elapsed = 2500 ms (+2500), ticks1 = 2500, ticks2 = 2500
[16:56:51.336]. #DBG: executing on_remove ({ 24579 })
[16:56:51.338/+33.534/131.2]. #XRAY: #DBG: m_dialogsToRender[0] releasing.
[16:56:51.585/+33.781/246.9]. #XRAY: #DBG: CWrapperBase() instance created
[16:56:51.604]. #DBG: main_menu created, namespace = ui_main_menu
[16:56:51.604]. #DBG: last binder update = 16:56:51.584, inventory { show = No, hide = Yes }, can use gadget = Yes, weapon hide = No
[16:56:51.609]. #DBG: Game paused changed to 1
[16:56:51.730]. #PERF: schedule.script executed 100 tasks