2024-11-19 13:50:05,315 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-19 13:50:05,316 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-19 13:50:05,316 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000751 2024-11-19 13:50:05,316 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 13:50:05,316 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 13:50:05,316 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 13:50:05,316 DEBUG (root) [MainThread] vcs type: git 2024-11-19 13:50:05,316 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000198 2024-11-19 13:50:05,316 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,316 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000408 2024-11-19 13:50:05,316 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-19 13:50:05,317 DEBUG (root) [MainThread] Start up timestamp 2024-11-19T13:50:05.UTC (2024-11-19T13:50:05.UTC) 2024-11-19 13:50:05,317 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000036 2024-11-19 13:50:05,317 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000005 2024-11-19 13:50:05,317 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000394 2024-11-19 13:50:05,317 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732024205.2928.352201uu instead of None 2024-11-19 13:50:05,317 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000149 2024-11-19 13:50:05,317 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732024205.317753 2024-11-19 13:50:05,317 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732024205.317753 2024-11-19 13:50:05,317 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732024205.317753 2024-11-19 13:50:05,318 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732024205.3182127 2024-11-19 13:50:05,318 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732024205.3182127 2024-11-19 13:50:05,318 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732024205.3182127 2024-11-19 13:50:05,318 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-19 13:50:05,330 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-19 13:50:05,330 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-19 13:50:05,331 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-19 13:50:05,331 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 13:50:05,331 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-19 13:50:05,331 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-19 13:50:05,332 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-19 13:50:05,332 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-19 13:50:05,332 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf` with `9` keys 2024-11-19 13:50:05,332 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 13:50:05,332 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-19 13:50:05,343 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-19 13:50:05,343 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-19 13:50:05,344 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732024205.344575 2024-11-19 13:50:05,344 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732024205.344575 2024-11-19 13:50:05,344 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732024205.344575 2024-11-19 13:50:05,344 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732024205.3447008 2024-11-19 13:50:05,344 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732024205.3447008 2024-11-19 13:50:05,344 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732024205.3447008 2024-11-19 13:50:05,345 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Add [('params', ), ('hide_token', ), ('state', ), ('display', ), ('custom_file_log', ), ('display_log', ), ('vcs_type', ), ('self_info', ), ('fetcher_params', ), ('use_universal_fetcher_everywhere', ), ('docker_config_path', ), ('hide_token2', ), ('fetchers_storage', ), ('fetcher', ), ('showstack', ), ('profile', ), ('mlockall', ), ('event_queue', ), ('changelist_store', ), ('evlog', ), ('dump_debug', )] to ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir', 'params', 'hide_token', 'state', 'display']) 2024-11-19 13:50:05,345 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 13:50:05,345 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-19 13:50:05,345 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-19 13:50:05,345 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000264 2024-11-19 13:50:05,347 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,347 DEBUG (root) [MainThread] Update replacements in 2024-11-19 13:50:05,347 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 13:50:05,347 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,347 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 13:50:05,347 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,347 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002275 2024-11-19 13:50:05,347 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000216 2024-11-19 13:50:05,348 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000043 2024-11-19 13:50:05,348 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,348 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000880 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000033 2024-11-19 13:50:05,349 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 13:50:05,349 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 13:50:05,349 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 13:50:05,349 DEBUG (root) [MainThread] vcs type: git 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000216 2024-11-19 13:50:05,349 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-19 13:50:05,349 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000099 2024-11-19 13:50:05,349 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000076 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-19 13:50:05,349 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000001 2024-11-19 13:50:05,350 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 13:50:05,350 DEBUG (root) [MainThread] Update replacements in 2024-11-19 13:50:05,350 DEBUG (root) [MainThread] Update replacements in 2024-11-19 13:50:05,350 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-19 13:50:05,350 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001109 2024-11-19 13:50:05,350 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000061 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000133 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-19 13:50:05,351 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000037 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-19 13:50:05,351 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000244 2024-11-19 13:50:05,351 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-19 13:50:05,352 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000387 2024-11-19 13:50:05,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.038046 2024-11-19 13:50:05,390 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-19 13:50:05,390 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732024205.3908997 2024-11-19 13:50:05,390 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732024205.3908997 2024-11-19 13:50:05,391 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732024205.3908997 2024-11-19 13:50:05,391 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732024205.391053 2024-11-19 13:50:05,391 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732024205.391053 2024-11-19 13:50:05,391 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732024205.391053 2024-11-19 13:50:05,515 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732024205.515628 2024-11-19 13:50:05,515 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732024205.515628 2024-11-19 13:50:05,515 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732024205.515628 2024-11-19 13:50:05,515 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732024205.5158298 2024-11-19 13:50:05,515 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732024205.5158298 2024-11-19 13:50:05,515 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732024205.5158298 2024-11-19 13:50:05,515 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732024205.515946 2024-11-19 13:50:05,515 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732024205.515946 2024-11-19 13:50:05,516 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732024205.515946 2024-11-19 13:50:05,516 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-19 13:50:05,516 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-19 13:50:05,516 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-19 13:50:05,516 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732024205.5161488 2024-11-19 13:50:05,516 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732024205.5161488 2024-11-19 13:50:05,516 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732024205.5161488 2024-11-19 13:50:05,516 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732024205.516382 2024-11-19 13:50:05,516 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732024205.516382 2024-11-19 13:50:05,516 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732024205.516382 2024-11-19 13:50:05,518 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-19 13:50:05,518 DEBUG (yalibrary.toolscache) [MainThread] Tools cache parameters: tc enabled=True, tc master=True, ac enabled=True, ac master=True, tc_lock_file=/home/runner/.ya/tools/v4/.cache_lock, ac_lock_file=/home/runner/.ya/build/cache/7/.cache_lock, binary=None, ini=None, tc_conf={}, ac_conf={'cas_logging': 'true', 'graph_info': 'true'}, gl_conf={} 2024-11-19 13:50:05,518 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-19 13:50:05,520 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.JALNgXdf3o' file to obtain bazel remote password 2024-11-19 13:50:05,520 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732024205.52091 2024-11-19 13:50:05,521 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732024205.52091 2024-11-19 13:50:05,521 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732024205.52091 2024-11-19 13:50:05,521 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-19 13:50:05,521 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-19 13:50:05,522 DEBUG (yalibrary.vcs) [Misc-01] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: ['/home/runner/actions_runner/_work/ydb/ydb'] 2024-11-19 13:50:05,522 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 13:50:05,522 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 13:50:05,523 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-19 13:50:05,524 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-19 13:50:05,526 DEBUG (build.graph) [MainThread] flags: { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CONSISTENT_DEBUG": "yes", "DEBUGINFO_LINES_ONLY": "yes", "DISABLE_FLAKE8_MIGRATIONS": "yes", "OPENSOURCE": "yes", "TESTS_REQUESTED": "yes", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_EAT_MY_DATA": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" } 2024-11-19 13:50:05,526 DEBUG (build.graph) [MainThread] host toolchain: { "bottle_name": "clang18", "build_type": "release", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CLANG_COVERAGE": "no", "CONSISTENT_DEBUG": "yes", "NO_DEBUGINFO": "yes", "OPENSOURCE": "yes", "TIDY": "no", "TOOL_BUILD_MODE": "yes", "TRAVERSE_RECURSE": "no", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "tool_var": "CLANG-1735056821" } 2024-11-19 13:50:05,527 DEBUG (build.graph) [MainThread] target toolchain: { "bottle_name": "clang18", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "SANITIZER_TYPE": "address" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "platform_name": "DEFAULT-LINUX-X86_64", "tool_var": "CLANG-1735056821" } 2024-11-19 13:50:05,527 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-19 13:50:05,527 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-19 13:50:05,527 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: try to fetch by {'darwin': {'uri': 'sbr:7448914799'}, 'darwin-arm64': {'uri': 'sbr:7448913101'}, 'linux': {'uri': 'sbr:7448917907'}, 'linux-aarch64': {'uri': 'sbr:7448911598'}, 'win32-clang-cl': {'uri': 'sbr:7448916360'}} for 'linux' 2024-11-19 13:50:05,527 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 13:50:05,527 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 7448917907 from sbr:7448917907 to /home/runner/.ya/tools/v4/7448917907 dir, post_process=0) 2024-11-19 13:50:05,527 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-19 13:50:05,529 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-19 13:50:05,537 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-19 13:50:05,537 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-19 13:50:05,537 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 13:50:05,537 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ya-tc/public.resources.json 2024-11-19 13:50:05,537 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: try to fetch by {'darwin': {'uri': 'sbr:6812263131'}, 'darwin-arm64': {'uri': 'sbr:6812261994'}, 'linux': {'uri': 'sbr:6812260917'}, 'linux-aarch64': {'uri': 'sbr:6812259929'}} for 'linux' 2024-11-19 13:50:05,538 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-19 13:50:05,538 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 6812260917 from sbr:6812260917 to /home/runner/.ya/tools/v4/6812260917 dir, post_process=0) 2024-11-19 13:50:05,538 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 13:50:05,538 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-19 13:50:05,538 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-19 13:50:05,538 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 13:50:05,538 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 13:50:05,538 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters done: id=6812260917, full_path=/home/runner/.ya/tools/v4/6812260917/ya-tc 2024-11-19 13:50:05,541 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2352, 12448, 'unix:/home/runner/.ya/tools/v4/psingkB7ueU.tmp') 2024-11-19 13:50:05,546 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 13:50:05,546 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-19 13:50:05,546 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-19 13:50:05,546 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.01931476593017578 2024-11-19 13:50:05,546 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732024205.5469205 2024-11-19 13:50:05,547 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_started=1732024205.5469205 2024-11-19 13:50:05,547 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_started - 1732024205.5469205 2024-11-19 13:50:05,547 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024205.547277 2024-11-19 13:50:05,547 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_started=1732024205.547277 2024-11-19 13:50:05,548 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_started - 1732024205.547277 2024-11-19 13:50:05,548 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732024205.5473814 2024-11-19 13:50:05,548 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732024205.5473814 2024-11-19 13:50:05,548 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732024205.5473814 2024-11-19 13:50:05,548 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732024205.547953 2024-11-19 13:50:05,548 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started=1732024205.547953 2024-11-19 13:50:05,548 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started - 1732024205.547953 2024-11-19 13:50:05,548 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-19 13:50:05,548 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732024205.5484378 2024-11-19 13:50:05,548 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732024205.5484378 2024-11-19 13:50:05,548 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732024205.5484378 2024-11-19 13:50:05,549 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024205.5484676 2024-11-19 13:50:05,549 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started=1732024205.5484676 2024-11-19 13:50:05,549 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started - 1732024205.5484676 2024-11-19 13:50:05,549 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-19 13:50:05,549 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --tags HEAD failed with exit code 128 2024-11-19 13:50:05,552 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-19 13:50:05,554 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'65d3726bde072809f33863b6bd114f541d34b7ca', author:b'Vadim Averin ', summary:b'Merge 4f58ca1cdb241aa5752b7c59ae2d3eced6095849 into 4652366d62c74c2771848361eba6abd67921c5ad', svn_id:b'' 2024-11-19 13:50:05,555 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Other data: Other info: Build by: runner Top src dir: /home/runner/actions_runner/_work/ydb/ydb Top build dir: /home/runner/.ya/build Hostname: ghrun-oagcqscbne Host information: Linux ghrun-oagcqscbne 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-19 13:50:05,555 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"65d3726bde072809f33863b6bd114f541d34b7ca\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"Vadim Averin \",\n \"ARCADIA_SOURCE_LAST_CHANGE\": -1,\n \"ARCADIA_SOURCE_PATH\": \"/home/runner/actions_runner/_work/ydb/ydb\",\n \"ARCADIA_SOURCE_REVISION\": -1,\n \"ARCADIA_SOURCE_URL\": \"\",\n \"ARCADIA_TAG\": \"\",\n \"BRANCH\": \"\",\n \"BUILD_DATE\": \"2024-11-19T13:50:05.000000Z\",\n \"BUILD_HOST\": \"ghrun-oagcqscbne\",\n \"BUILD_TIMESTAMP\": 1732024205,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: 65d3726bde072809f33863b6bd114f541d34b7ca\\n Branch: \\n Author: Vadim Averin \\n Summary: Merge 4f58ca1cdb241aa5752b7c59ae2d3eced6095849 into 4652366d62c74c2771848361eba6abd67921c5ad\\n\\nOther info:\\n Build by: runner\\n Top src dir: /home/runner/actions_runner/_work/ydb/ydb\\n Top build dir: /home/runner/.ya/build\\n Hostname: ghrun-oagcqscbne\\n Host information: \\n Linux ghrun-oagcqscbne 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64\\n\\n \",\n \"SCM_DATA\": \"Git info:\\n Commit: 65d3726bde072809f33863b6bd114f541d34b7ca\\n Branch: \\n Author: Vadim Averin \\n Summary: Merge 4f58ca1cdb241aa5752b7c59ae2d3eced6095849 into 4652366d62c74c2771848361eba6abd67921c5ad\\n\",\n \"VCS\": \"git\"\n}" 2024-11-19 13:50:05,555 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.03353452682495117 2024-11-19 13:50:05,580 DEBUG (build.genconf) [Misc-03] Generating conf into /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 13:50:05,581 DEBUG (build.genconf) [Misc-02] Generating conf into /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 13:50:05,707 DEBUG (build.genconf) [Misc-02] Copy conf /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf 2024-11-19 13:50:05,707 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-19 13:50:05,707 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732024205.7073977 2024-11-19 13:50:05,707 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished=1732024205.7073977 2024-11-19 13:50:05,707 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished - 1732024205.7073977 2024-11-19 13:50:05,707 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732024205.707623 2024-11-19 13:50:05,707 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_started=1732024205.707623 2024-11-19 13:50:05,707 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_started - 1732024205.707623 2024-11-19 13:50:05,707 DEBUG (build.ymake2) [Misc-02] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-pic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f2fbb57b240>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/makefiles.dart'} 2024-11-19 13:50:05,708 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-19 13:50:05,708 DEBUG (build.genconf) [Misc-03] Copy conf /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf 2024-11-19 13:50:05,708 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-19 13:50:05,708 DEBUG (core.event_handling.base_subscriber) [Misc-02] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 13:50:05,708 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-19 13:50:05,708 DEBUG (build.ymake2.run_ymake) [Misc-02] run: '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpau4kzmh9 --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 13:50:05,709 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-19 13:50:05,709 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024205.7092044 2024-11-19 13:50:05,710 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished=1732024205.7092044 2024-11-19 13:50:05,711 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished - 1732024205.7092044 2024-11-19 13:50:05,711 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024205.7111979 2024-11-19 13:50:05,711 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_started=1732024205.7111979 2024-11-19 13:50:05,711 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_started - 1732024205.7111979 2024-11-19 13:50:05,711 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-nopic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f2fbc2704a0>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/makefiles.dart'} 2024-11-19 13:50:05,711 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-19 13:50:05,711 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-19 13:50:05,712 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-19 13:50:05,712 DEBUG (build.ymake2.run_ymake) [Misc-03] run: '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpf0mvo8fi --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 13:50:05,714 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732024205714754,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024205714874,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732024205714907,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024205714916,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732024205714920,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732024205715160,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:05,715 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732024205715165,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732024205717180,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":1} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024205717275,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732024205717303,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024205717311,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732024205717315,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:05,717 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732024205717558,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:05,718 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732024205717562,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:05,868 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732024205868069,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:05,868 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732024205868091,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:05,869 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732024205869448,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:05,869 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732024205869459,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:05,870 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732024205870602,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:05,870 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732024205870609,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:05,871 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732024205871385,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:05,871 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732024205871404,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:05,872 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732024205872902,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:05,873 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732024205872914,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:05,874 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732024205874100,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:05,874 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732024205874110,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:05,986 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732024205985850,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:05,989 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732024205989438,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,016 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016469,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,016 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016488,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016492,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016577,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732024206016581,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016638,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016642,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016645,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,017 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016649,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016652,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016655,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016659,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206016662,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732024206017494,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206017503,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732024206017509,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732024206017513,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732024206017518,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,018 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206017523,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,019 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732024206017527,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-19 13:50:06,019 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732024206017531,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-19 13:50:06,019 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732024206017534,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,020 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020332,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,020 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020351,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,020 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020355,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,020 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020427,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732024206020431,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020469,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020474,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020477,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020481,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020484,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020488,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020492,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,021 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206020495,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732024206021322,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206021329,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732024206021336,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732024206021339,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732024206021343,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206021348,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732024206021352,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732024206021356,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-19 13:50:06,022 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732024206021359,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,112 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09462250946,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732024206112172,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,112 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732024206112196,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,112 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206112205,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,112 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206112212,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,113 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732024206112263,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,113 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"TIncFixer stats: 0 resolved to nothing, 0 resolved to existing, 0 resolved to new.\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206112344,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,113 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732024206112348,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,113 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9020215; load time = 9355; loaded MD5 time = 749; Max loaded MD5 time = 8; loaded count = 2174; max load time = 32; mapped size = 40632376; mapped MD5 time = 6326; Max mapped MD5 time = 401; mapped count = 130; map time = 660; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3204; lstat min us = 1; lstat avr us = 1; lstat max us = 21; opendir count = 388; opendir sum us = 0; opendir min us = 0; opendir avr us = 0; opendir max us = 0; readdir count = 2837; readdir sum us = 2048; readdir min us = 0; readdir avr us = 0; readdir max us = 39; (opendir + readdir) sum us = 2048; (lstat + opendir + readdir) sum us = 5252;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732024206112382,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,113 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2048,"LoadTime":9355,"LoadedCount":2174,"LoadedMD5Time":749,"LoadedSize":9020215,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5252,"LstatMaxUs":21,"LstatMinUs":1,"LstatSumUs":3204,"MapTime":660,"MappedCount":130,"MappedMD5Time":6326,"MappedSize":40632376,"MaxLoadTime":32,"MaxLoadedMD5Time":8,"MaxMappedMD5Time":401,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":39,"ReaddirMinUs":0,"ReaddirSumUs":2048,"_timestamp":1732024206112394,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9020215,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732024206112406,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9355,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732024206112410,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732024206112413,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732024206112416,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":660,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732024206112419,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732024206112422,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.09311299854,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732024206114482,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732024206112424,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,114 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732024206114501,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9020215; load time = 9355; loaded count = 2174; max load time = 32;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732024206112432,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206114509,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":9355,"LoadedCount":2174,"LoadedSize":9020215,"MaxLoadTime":32,"_timestamp":1732024206112437,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206114516,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 5; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732024206112448,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732024206114562,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":5,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732024206112453,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"TIncFixer stats: 0 resolved to nothing, 0 resolved to existing, 0 resolved to new.\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206114640,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,115 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"count = 8; includes count = 12; unique count = 8; size = 1264; unique size = 853;","Mod":"unimp","Sub":"ya.make parsing stats","Type":"Debug","_timestamp":1732024206112464,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732024206114644,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732024206112469,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nodes count = 2825; edges count = 12363; files count = 3263; commands count = 142;","Mod":"unimp","Sub":"DepGraph stats","Type":"Debug","_timestamp":1732024206112484,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9020215; load time = 8984; loaded MD5 time = 761; Max loaded MD5 time = 9; loaded count = 2174; max load time = 22; mapped size = 40632376; mapped MD5 time = 6113; Max mapped MD5 time = 376; mapped count = 130; map time = 655; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3189; lstat min us = 0; lstat avr us = 1; lstat max us = 62; opendir count = 388; opendir sum us = 0; opendir min us = 0; opendir avr us = 0; opendir max us = 0; readdir count = 2837; readdir sum us = 1841; readdir min us = 0; readdir avr us = 0; readdir max us = 38; (opendir + readdir) sum us = 1841; (lstat + opendir + readdir) sum us = 5030;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732024206114677,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732024206112488,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732024206112494,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":1841,"LoadTime":8984,"LoadedCount":2174,"LoadedMD5Time":761,"LoadedSize":9020215,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5030,"LstatMaxUs":62,"LstatMinUs":0,"LstatSumUs":3189,"MapTime":655,"MappedCount":130,"MappedMD5Time":6113,"MappedSize":40632376,"MaxLoadTime":22,"MaxLoadedMD5Time":9,"MaxMappedMD5Time":376,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":38,"ReaddirMinUs":0,"ReaddirSumUs":1841,"_timestamp":1732024206114689,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732024206112497,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-19 13:50:06,116 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9020215,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732024206114701,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Includes attempts = 0; From cache = 0; Resolve AsKnown total = 0; Resolve AsKnown from cache = 0;","Mod":"unimp","Sub":"Resolving stats","Type":"Debug","_timestamp":1732024206112504,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":8984,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732024206114705,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732024206112509,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732024206114708,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732024206112512,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732024206114711,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"accessed = 19; loaded = 0; outdated = 0; parsed = 19; total = 19;","Mod":"unimp","Sub":"TModules stats","Type":"Debug","_timestamp":1732024206112613,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":655,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732024206114714,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732024206112617,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-19 13:50:06,117 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732024206114716,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732024206112621,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732024206114719,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732024206112625,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9020215; load time = 8984; loaded count = 2174; max load time = 22;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732024206114726,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732024206112628,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":8984,"LoadedCount":2174,"LoadedSize":9020215,"MaxLoadTime":22,"_timestamp":1732024206114731,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732024206112630,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 1; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732024206114741,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":1,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732024206114746,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-19 13:50:06,118 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732024206112641,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"count = 8; includes count = 12; unique count = 8; size = 1264; unique size = 853;","Mod":"unimp","Sub":"ya.make parsing stats","Type":"Debug","_timestamp":1732024206114757,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732024206113217,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732024206114762,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732024206113219,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nodes count = 2825; edges count = 12363; files count = 3263; commands count = 142;","Mod":"unimp","Sub":"DepGraph stats","Type":"Debug","_timestamp":1732024206114778,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732024206115913,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732024206114781,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732024206115917,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732024206114786,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,119 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732024206114789,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-19 13:50:06,120 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-release-asan-pic). Tool targets: set() 2024-11-19 13:50:06,120 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732024206115966,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,120 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Includes attempts = 0; From cache = 0; Resolve AsKnown total = 0; Resolve AsKnown from cache = 0;","Mod":"unimp","Sub":"Resolving stats","Type":"Debug","_timestamp":1732024206114796,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,120 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732024206115968,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,120 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732024206114800,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-19 13:50:06,120 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732024206115970,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732024206114804,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732024206115972,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"accessed = 19; loaded = 0; outdated = 0; parsed = 19; total = 19;","Mod":"unimp","Sub":"TModules stats","Type":"Debug","_timestamp":1732024206114901,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732024206115975,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732024206114905,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732024206115977,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732024206114909,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732024206116033,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732024206114912,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732024206116035,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,121 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732024206114915,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Total cache size on save = 428512; Diagnostics cache size on save = 112; Graph cache size on save = 91616; Parsers cache size on save = 224; Modules table size on save = 3079; Times table size on save = 16; Names table size on save = 332624; Commands cache size on save = 272;","Mod":"unimp","Sub":"Internal cache stats","Type":"Debug","_timestamp":1732024206116937,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732024206114918,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsSize":272,"DiagnosticsCacheSize":112,"GraphCacheSize":91616,"ModulesTableSize":3079,"NamesTableSize":332624,"ParsersCacheSize":224,"TimesTableSize":16,"TotalCacheSize":428512,"_timestamp":1732024206116948,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206116956,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206116961,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732024206114927,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732024206118236,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732024206115507,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732024206118242,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,122 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732024206115509,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,123 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732024206118665,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-19 13:50:06,123 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732024206118221,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,123 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732024206118697,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,123 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732024206118226,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,123 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732024206118700,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,123 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-release-asan-nopic). Tool targets: set() 2024-11-19 13:50:06,123 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732024206.1235368 2024-11-19 13:50:06,123 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732024206.1235368 2024-11-19 13:50:06,123 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732024206.1235368 2024-11-19 13:50:06,123 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-19 13:50:06,123 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732024206.1238546 2024-11-19 13:50:06,123 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732024206.1238546 2024-11-19 13:50:06,123 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732024206.1238546 2024-11-19 13:50:06,124 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732024206.1240451 2024-11-19 13:50:06,124 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732024206.1240451 2024-11-19 13:50:06,124 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732024206.1240451 2024-11-19 13:50:06,124 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732024206.124245 2024-11-19 13:50:06,124 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732024206.124245 2024-11-19 13:50:06,124 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732024206.124245 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732024206119133,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732024206118274,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206119248,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732024206118277,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732024206119252,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732024206118279,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,124 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732024206119254,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732024206118281,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206119257,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732024206118283,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732024206119289,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732024206118285,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732024206119291,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732024206118341,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732024206119295,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732024206118343,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732024206119437,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,125 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Total cache size on save = 428512; Diagnostics cache size on save = 112; Graph cache size on save = 91616; Parsers cache size on save = 224; Modules table size on save = 3079; Times table size on save = 16; Names table size on save = 332624; Commands cache size on save = 272;","Mod":"unimp","Sub":"Internal cache stats","Type":"Debug","_timestamp":1732024206119199,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732024206119457,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsSize":272,"DiagnosticsCacheSize":112,"GraphCacheSize":91616,"ModulesTableSize":3079,"NamesTableSize":332624,"ParsersCacheSize":224,"TimesTableSize":16,"TotalCacheSize":428512,"_timestamp":1732024206119209,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732024206120108,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206119216,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732024206120161,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206119220,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732024206120169,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732024206120224,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206120173,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732024206120229,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732024206120176,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732024206120627,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732024206123054,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732024206120656,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732024206123059,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732024206120658,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732024206121081,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206123071,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206121195,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732024206121199,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732024206121201,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206121204,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732024206121234,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732024206121236,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732024206121240,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732024206121377,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732024206121395,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732024206122060,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732024206122115,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732024206122122,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206122126,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732024206122130,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,128 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732024206124977,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732024206124981,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,129 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206124992,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Loaded cache items = 0; Added cache items = 0; Saved old cache items = 0; Saved new cache items = 0; Saved total cache items = 0; Loaded full match items = 0; Full match requests = 0; Successful full matches = 0; Loaded partial match items = 0; Partial match requests = 0; Successful partial matches = 0; Fully rendered nodes = 0; Partially rendered nodes = 0; Nodes restored without rendering = 0;","Mod":"unimp","Sub":"JSON cache stats","Type":"Debug","_timestamp":1732024206130245,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"AddedItems":0,"FullMatchLoadedItems":0,"FullMatchRequests":0,"FullMatchSuccess":0,"FullyRendered":0,"LoadedItems":0,"NewItemsSaved":0,"NoRendered":0,"OldItemsSaved":0,"PartialMatchLoadedItems":0,"PartialMatchRequests":0,"PartialMatchSuccess":0,"PartiallyRendered":0,"TotalItemsSaved":0,"_timestamp":1732024206130252,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206130261,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.007206054455,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732024206130269,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732024206130273,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732024206130276,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206131004,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732024206131010,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732024206131021,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Loaded nodes = 0; Skipped nodes = 0; Discarded nodes = 0; Loaded loops = 0; Skipped loops = 0; Discarded loops = 0; Saved nodes = 2392; Saved loops = 0; Really all nodes no rendered = 0;","Mod":"unimp","Sub":"Uids cache stats","Type":"Debug","_timestamp":1732024206131033,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"DiscardedLoops":0,"DiscardedNodes":0,"LoadedLoops":0,"LoadedNodes":0,"SavedLoops":0,"SavedNodes":2392,"SkippedLoops":0,"SkippedNodes":0,"_timestamp":1732024206131038,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01093140342,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732024206131104,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732024206131108,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732024206131128,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,131 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732024206131131,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,132 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"module environment initialization calls = 13; actual module environment initializations = 13;","Mod":"unimp","Sub":"TMakeCommand stats","Type":"Debug","_timestamp":1732024206131138,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,132 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732024206131142,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-19 13:50:06,132 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Loaded cache items = 0; Added cache items = 0; Saved old cache items = 0; Saved new cache items = 0; Saved total cache items = 0; Loaded full match items = 0; Full match requests = 0; Successful full matches = 0; Loaded partial match items = 0; Partial match requests = 0; Successful partial matches = 0; Fully rendered nodes = 0; Partially rendered nodes = 0; Nodes restored without rendering = 0;","Mod":"unimp","Sub":"JSON cache stats","Type":"Debug","_timestamp":1732024206132105,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"AddedItems":0,"FullMatchLoadedItems":0,"FullMatchRequests":0,"FullMatchSuccess":0,"FullyRendered":0,"LoadedItems":0,"NewItemsSaved":0,"NoRendered":0,"OldItemsSaved":0,"PartialMatchLoadedItems":0,"PartialMatchRequests":0,"PartialMatchSuccess":0,"PartiallyRendered":0,"TotalItemsSaved":0,"_timestamp":1732024206132112,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206132120,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.007143165682,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732024206132128,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732024206132132,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206133249,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732024206132135,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206133265,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206132866,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732024206133270,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732024206132872,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,133 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206133282,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732024206132883,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3031,"Sub":"","Type":"Debug","_timestamp":1732024206133292,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Loaded nodes = 0; Skipped nodes = 0; Discarded nodes = 0; Loaded loops = 0; Skipped loops = 0; Discarded loops = 0; Saved nodes = 2392; Saved loops = 0; Really all nodes no rendered = 0;","Mod":"unimp","Sub":"Uids cache stats","Type":"Debug","_timestamp":1732024206132894,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732024206133951,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-03] Configure message {"DiscardedLoops":0,"DiscardedNodes":0,"LoadedLoops":0,"LoadedNodes":0,"SavedLoops":0,"SavedNodes":2392,"SkippedLoops":0,"SkippedNodes":0,"_timestamp":1732024206132900,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4191675373,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732024206133959,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 13:50:06,134 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01085075661,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732024206132976,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732024206133963,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":0} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732024206132980,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732024206133001,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732024206133004,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"module environment initialization calls = 13; actual module environment initializations = 13;","Mod":"unimp","Sub":"TMakeCommand stats","Type":"Debug","_timestamp":1732024206133011,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732024206133015,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206134787,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206134798,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732024206134803,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-19 13:50:06,135 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206134814,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,136 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3033,"Sub":"","Type":"Debug","_timestamp":1732024206134825,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 13:50:06,136 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732024206135430,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 13:50:06,136 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4182433936,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732024206135437,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 13:50:06,136 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732024206135443,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":1} 2024-11-19 13:50:06,138 DEBUG (build.ymake2.run_ymake) [Misc-02] run '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpau4kzmh9 --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 13:50:06,139 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732024205.70814061164856,"finish":1732024205.708904981613159,"duration":0.0007643699646},"execution":{"start":1732024205.708905696868896,"finish":1732024206.13887357711792,"duration":0.429967880249023},"postprocessing":{"start":1732024206.138875961303711,"finish":1732024206.139020681381226,"duration":0.000144720077515}},"purpose":"default-linux-x86_64-release-asan-pic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732024205.714754104614258,"finish":1732024206.133963108062744,"duration":0.419209003448486},"Load configuration from cache":{"start":1732024205.714906930923462,"finish":1732024205.714920043945312,"duration":0.000013113021851},"Load configuration (no cache)":{"start":1732024205.71516489982605,"finish":1732024205.868068933486938,"duration":0.152904033660889},"Compile and RecalcAll conditions":{"start":1732024205.868091106414795,"finish":1732024205.869447946548462,"duration":0.001356840133667},"Fold global commands":{"start":1732024205.869458913803101,"finish":1732024205.870601892471314,"duration":0.001142978668213},"Load plugins":{"start":1732024205.870609045028686,"finish":1732024205.985850095748901,"duration":0.115241050720215},"ymake main":{"start":1732024206.016581058502197,"finish":1732024206.13395094871521,"duration":0.117369890213013},"Build graph":{"start":1732024206.017518043518066,"finish":1732024206.112512111663818,"duration":0.094994068145752},"Configure graph":{"start":1732024206.017534017562866,"finish":1732024206.11219596862793,"duration":0.094661951065063},"Find Lost Includes":{"start":1732024206.112262964248657,"finish":1732024206.112348079681396,"duration":0.000085115432739},"Set reachable nodes":{"start":1732024206.112641096115112,"finish":1732024206.113217115402222,"duration":0.000576019287109},"Sort edges":{"start":1732024206.113219022750854,"finish":1732024206.115912914276123,"duration":0.002693891525269},"Report Foreign Platform Events":{"start":1732024206.115916967391968,"finish":1732024206.115966081619263,"duration":0.000049114227295},"Check blacklist":{"start":1732024206.115967988967896,"finish":1732024206.115969896316528,"duration":0.000001907348633},"Check isolated projects":{"start":1732024206.11597204208374,"finish":1732024206.115974903106689,"duration":0.000002861022949},"Report Configure Events":{"start":1732024206.115977048873901,"finish":1732024206.11603307723999,"duration":0.000056028366089},"Save and compact":{"start":1732024206.116034984588623,"finish":1732024206.118236064910889,"duration":0.002201080322266},"Detect loops":{"start":1732024206.118242025375366,"finish":1732024206.118696928024292,"duration":0.000454902648926},"Apply Dependency Management":{"start":1732024206.11870002746582,"finish":1732024206.119254112243652,"duration":0.000554084777832},"Save Dependency management cache":{"start":1732024206.119132995605469,"finish":1732024206.11925196647644,"duration":0.000118970870972},"Fill package outputs":{"start":1732024206.119288921356201,"finish":1732024206.119291067123413,"duration":0.000002145767212},"Check Transitive Requirements":{"start":1732024206.119294881820679,"finish":1732024206.119436979293823,"duration":0.000142097473145},"Find missing peerdirs":{"start":1732024206.119457006454468,"finish":1732024206.120107889175415,"duration":0.000650882720947},"Export JSON":{"start":1732024206.120161056518555,"finish":1732024206.131128072738647,"duration":0.010967016220093},"Write JSON":{"start":1732024206.120168924331665,"finish":1732024206.131108045578003,"duration":0.010939121246338},"Visit JSON":{"start":1732024206.120176076889038,"finish":1732024206.123054027557373,"duration":0.002877950668335},"Render JSON":{"start":1732024206.123059034347534,"finish":1732024206.130273103713989,"duration":0.007214069366455},"Save Uids cache":{"start":1732024206.130275964736938,"finish":1732024206.131010055541992,"duration":0.000734090805054}},"metrics":{"File access":{"loaded size":9020215,"load time":9355,"loaded MD5 time":749,"Max loaded MD5 time":8,"loaded count":2174,"max load time":32,"mapped size":40632376,"mapped MD5 time":6326,"Max mapped MD5 time":401,"mapped count":130,"map time":660,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3204,"lstat min us":1,"lstat avr us":1,"lstat max us":21,"opendir count":388,"opendir sum us":0,"opendir min us":0,"opendir avr us":0,"opendir max us":0,"readdir count":2837,"readdir sum us":2048,"readdir min us":0,"readdir avr us":0,"readdir max us":39,"(opendir + readdir) sum us":2048,"(lstat + opendir + readdir) sum us":5252},"Parsing":{"parse time":5,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpau4kzmh9","--config","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.js1j0ne8/makefiles.dart","--dump-build-plan","-","--quiet","--events","dGT","/home/runner/actions_runner/_work/ydb/ydb"],"has_changelist":false,"predicts_structural_changes":true,"bypass_is_enabled":false,"bypass_is_triggered":false,"has_content_changes":true,"has_structural_changes":true,"has_rendered_node_changes":true,"exit_code":0,"duration":0.429967880249023} 2024-11-19 13:50:06,139 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732024206.1396039 2024-11-19 13:50:06,139 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732024206.1396039 2024-11-19 13:50:06,139 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732024206.1396039 2024-11-19 13:50:06,140 DEBUG (build.ymake2.run_ymake) [Misc-03] run '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpf0mvo8fi --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 13:50:06,140 DEBUG (core.event_handling.base_subscriber) [Misc-03] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 13:50:06,140 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732024206.1408343 2024-11-19 13:50:06,141 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732024205.71164059638977,"finish":1732024205.712157249450684,"duration":0.000516653060913},"execution":{"start":1732024205.712157964706421,"finish":1732024206.140276670455933,"duration":0.428118705749512},"postprocessing":{"start":1732024206.140278339385986,"finish":1732024206.14051628112793,"duration":0.000237941741943}},"purpose":"default-linux-x86_64-release-asan-nopic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732024205.717180013656616,"finish":1732024206.135442972183228,"duration":0.418262958526611},"Load configuration from cache":{"start":1732024205.717303037643433,"finish":1732024205.717314958572388,"duration":0.000011920928955},"Load configuration (no cache)":{"start":1732024205.717561960220337,"finish":1732024205.871385097503662,"duration":0.153823137283325},"Compile and RecalcAll conditions":{"start":1732024205.871403932571411,"finish":1732024205.872901916503906,"duration":0.001497983932495},"Fold global commands":{"start":1732024205.87291407585144,"finish":1732024205.874099969863892,"duration":0.001185894012451},"Load plugins":{"start":1732024205.874109983444214,"finish":1732024205.989438056945801,"duration":0.115328073501587},"ymake main":{"start":1732024206.020431041717529,"finish":1732024206.135430097579956,"duration":0.114999055862427},"Build graph":{"start":1732024206.021342992782593,"finish":1732024206.114804029464722,"duration":0.093461036682129},"Configure graph":{"start":1732024206.021358966827393,"finish":1732024206.114500999450684,"duration":0.093142032623291},"Find Lost Includes":{"start":1732024206.114562034606934,"finish":1732024206.114644050598145,"duration":0.000082015991211},"Set reachable nodes":{"start":1732024206.114927053451538,"finish":1732024206.115506887435913,"duration":0.000579833984375},"Sort edges":{"start":1732024206.115509033203125,"finish":1732024206.118221044540405,"duration":0.00271201133728},"Report Foreign Platform Events":{"start":1732024206.118226051330566,"finish":1732024206.118273973464966,"duration":0.000047922134399},"Check blacklist":{"start":1732024206.118277072906494,"finish":1732024206.118278980255127,"duration":0.000001907348633},"Check isolated projects":{"start":1732024206.11828088760376,"finish":1732024206.118283033370972,"duration":0.000002145767212},"Report Configure Events":{"start":1732024206.118284940719604,"finish":1732024206.118340969085693,"duration":0.000056028366089},"Save and compact":{"start":1732024206.118343114852905,"finish":1732024206.120223999023438,"duration":0.001880884170532},"Detect loops":{"start":1732024206.120229005813599,"finish":1732024206.12065601348877,"duration":0.000427007675171},"Apply Dependency Management":{"start":1732024206.120657920837402,"finish":1732024206.121201038360596,"duration":0.000543117523193},"Save Dependency management cache":{"start":1732024206.121081113815308,"finish":1732024206.121198892593384,"duration":0.000117778778076},"Fill package outputs":{"start":1732024206.121233940124512,"finish":1732024206.121236085891724,"duration":0.000002145767212},"Check Transitive Requirements":{"start":1732024206.121239900588989,"finish":1732024206.121376991271973,"duration":0.000137090682983},"Find missing peerdirs":{"start":1732024206.121395111083984,"finish":1732024206.122060060501099,"duration":0.000664949417114},"Export JSON":{"start":1732024206.122114896774292,"finish":1732024206.133001089096069,"duration":0.010886192321777},"Write JSON":{"start":1732024206.122122049331665,"finish":1732024206.132980108261108,"duration":0.010858058929443},"Visit JSON":{"start":1732024206.122129917144775,"finish":1732024206.124977111816406,"duration":0.002847194671631},"Render JSON":{"start":1732024206.124980926513672,"finish":1732024206.132132053375244,"duration":0.007151126861572},"Save Uids cache":{"start":1732024206.132134914398193,"finish":1732024206.132872104644775,"duration":0.000737190246582}},"metrics":{"File access":{"loaded size":9020215,"load time":8984,"loaded MD5 time":761,"Max loaded MD5 time":9,"loaded count":2174,"max load time":22,"mapped size":40632376,"mapped MD5 time":6113,"Max mapped MD5 time":376,"mapped count":130,"map time":655,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3189,"lstat min us":0,"lstat avr us":1,"lstat max us":62,"opendir count":388,"opendir sum us":0,"opendir min us":0,"opendir avr us":0,"opendir max us":0,"readdir count":2837,"readdir sum us":1841,"readdir min us":0,"readdir avr us":0,"readdir max us":38,"(opendir + readdir) sum us":1841,"(lstat + opendir + readdir) sum us":5030},"Parsing":{"parse time":1,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpf0mvo8fi","--config","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.hu3jwtoa/makefiles.dart","--dump-build-plan","-","--quiet","--events","dGT","/home/runner/actions_runner/_work/ydb/ydb"],"has_changelist":false,"predicts_structural_changes":true,"bypass_is_enabled":false,"bypass_is_triggered":false,"has_content_changes":true,"has_structural_changes":true,"has_rendered_node_changes":true,"exit_code":0,"duration":0.428118705749512} 2024-11-19 13:50:06,141 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732024206.1408343 2024-11-19 13:50:06,141 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732024206.1408343 2024-11-19 13:50:06,141 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732024206.141217 2024-11-19 13:50:06,141 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732024206.141217 2024-11-19 13:50:06,141 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732024206.141217 2024-11-19 13:50:06,141 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732024206.141344 2024-11-19 13:50:06,141 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_finished=1732024206.141344 2024-11-19 13:50:06,141 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_finished - 1732024206.141344 2024-11-19 13:50:06,141 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732024206.1418152 2024-11-19 13:50:06,141 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_started=1732024206.1418152 2024-11-19 13:50:06,141 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_started - 1732024206.1418152 2024-11-19 13:50:06,145 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-19 13:50:06,145 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732024206.1458962 2024-11-19 13:50:06,145 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.00030684471130371094 2024-11-19 13:50:06,146 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732024206.1458962 2024-11-19 13:50:06,146 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732024206.1458962 2024-11-19 13:50:06,146 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024206.1463094 2024-11-19 13:50:06,146 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_finished=1732024206.1463094 2024-11-19 13:50:06,146 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_finished - 1732024206.1463094 2024-11-19 13:50:06,146 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732024206.1463537 2024-11-19 13:50:06,146 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_finished=1732024206.1463537 2024-11-19 13:50:06,146 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_finished - 1732024206.1463537 2024-11-19 13:50:06,146 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024206.1465387 2024-11-19 13:50:06,146 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_started=1732024206.1465387 2024-11-19 13:50:06,146 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_started - 1732024206.1465387 2024-11-19 13:50:06,147 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732024206.1469245 2024-11-19 13:50:06,147 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_finished=1732024206.1469245 2024-11-19 13:50:06,147 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_finished - 1732024206.1469245 2024-11-19 13:50:06,147 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-19 13:50:06,147 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.0001418590545654297 2024-11-19 13:50:06,147 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024206.1475794 2024-11-19 13:50:06,147 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_finished=1732024206.1475794 2024-11-19 13:50:06,147 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_finished - 1732024206.1475794 2024-11-19 13:50:06,147 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732024206.1477828 2024-11-19 13:50:06,147 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_finished=1732024206.1477828 2024-11-19 13:50:06,147 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_finished - 1732024206.1477828 2024-11-19 13:50:06,148 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-release-asan: 0.023756027221679688 2024-11-19 13:50:06,148 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732024206.148107 2024-11-19 13:50:06,148 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732024206.148107 2024-11-19 13:50:06,148 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732024206.148107 2024-11-19 13:50:06,148 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732024206.1483393 2024-11-19 13:50:06,148 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732024206.1483393 2024-11-19 13:50:06,148 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732024206.1483393 2024-11-19 13:50:06,148 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-release-asan: 0.0004718303680419922 2024-11-19 13:50:06,148 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732024206.1485078 2024-11-19 13:50:06,148 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732024206.1485078 2024-11-19 13:50:06,148 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732024206.1485078 2024-11-19 13:50:06,148 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732024206.1486359 2024-11-19 13:50:06,148 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732024206.1486359 2024-11-19 13:50:06,148 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732024206.1486359 2024-11-19 13:50:06,148 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-release-asan: 0.00031280517578125 2024-11-19 13:50:06,148 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-19 13:50:06,148 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732024206.1488595 2024-11-19 13:50:06,148 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732024206.1488595 2024-11-19 13:50:06,148 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732024206.1488595 2024-11-19 13:50:06,149 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732024206.1490002 2024-11-19 13:50:06,149 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732024206.1490002 2024-11-19 13:50:06,149 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732024206.1490002 2024-11-19 13:50:06,149 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-release-asan: 0.00033593177795410156 2024-11-19 13:50:06,149 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-19 13:50:06,149 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732024206.149188 2024-11-19 13:50:06,149 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732024206.149188 2024-11-19 13:50:06,149 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732024206.149188 2024-11-19 13:50:06,149 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-19 13:50:06,149 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732024206.1498494 2024-11-19 13:50:06,150 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732024206.1498494 2024-11-19 13:50:06,150 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732024206.1498494 2024-11-19 13:50:06,150 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732024206.1502512 2024-11-19 13:50:06,150 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732024206.1502512 2024-11-19 13:50:06,150 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732024206.1502512 2024-11-19 13:50:06,150 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-19 13:50:06,164 DEBUG (build.genconf) [Misc-04] Generating conf into /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'IS_CROSS_TOOLS=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 13:50:06,283 DEBUG (build.genconf) [Misc-04] Copy conf /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf 2024-11-19 13:50:06,283 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-19 13:50:06,283 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732024206.2836356 2024-11-19 13:50:06,283 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732024206.2836356 2024-11-19 13:50:06,283 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732024206.2836356 2024-11-19 13:50:06,283 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732024206.2838283 2024-11-19 13:50:06,283 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732024206.2838283 2024-11-19 13:50:06,283 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732024206.2838283 2024-11-19 13:50:06,283 DEBUG (build.ymake2) [Misc-04] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'test_tool_tc1-nopic-global', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3', '/home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f2fbb57b740>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/makefiles.dart'} 2024-11-19 13:50:06,284 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-19 13:50:06,284 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-19 13:50:06,284 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 13:50:06,284 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-19 13:50:06,284 DEBUG (build.ymake2.run_ymake) [Misc-04] run: '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpaa0pqeni --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' with env: { } 2024-11-19 13:50:06,647 DEBUG (build.ymake2.run_ymake) [Misc-04] run '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpaa0pqeni --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' finished 2024-11-19 13:50:06,647 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 13:50:06,647 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732024206.284206390380859,"finish":1732024206.284750699996948,"duration":0.000544309616089},"execution":{"start":1732024206.284751176834106,"finish":1732024206.64731240272522,"duration":0.362561225891113},"postprocessing":{"start":1732024206.647315979003906,"finish":1732024206.647648096084595,"duration":0.000332117080688}},"purpose":"test_tool_tc1-nopic-global","caches":{},"stages":{},"metrics":{},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732024205.2928.352201uu/yatmpaa0pqeni","--config","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732024205.2928.352201uu/gen_graph.__8ylreq/makefiles.dart","--dump-build-plan","-","--quiet","--events","PSLGE","/home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool","/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools","/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2","/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3","/home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib"],"exit_code":0,"duration":0.362561225891113} 2024-11-19 13:50:06,648 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732024206.6480541 2024-11-19 13:50:06,648 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732024206.6480541 2024-11-19 13:50:06,648 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732024206.6480541 2024-11-19 13:50:06,648 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732024206.6483028 2024-11-19 13:50:06,648 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732024206.6483028 2024-11-19 13:50:06,648 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732024206.6483028 2024-11-19 13:50:06,648 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732024206.6484625 2024-11-19 13:50:06,648 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732024206.6484625 2024-11-19 13:50:06,648 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732024206.6484625 2024-11-19 13:50:06,648 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732024206.6487112 2024-11-19 13:50:06,648 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732024206.6487112 2024-11-19 13:50:06,648 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732024206.6487112 2024-11-19 13:50:06,649 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732024206.6491947 2024-11-19 13:50:06,649 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732024206.6491947 2024-11-19 13:50:06,649 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732024206.6491947 2024-11-19 13:50:06,650 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732024206.6509516 2024-11-19 13:50:06,651 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732024206.6509516 2024-11-19 13:50:06,651 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732024206.6509516 2024-11-19 13:50:06,651 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732024206.651162 2024-11-19 13:50:06,651 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732024206.651162 2024-11-19 13:50:06,651 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732024206.651162 2024-11-19 13:50:06,651 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732024206.6513162 2024-11-19 13:50:06,651 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732024206.6513162 2024-11-19 13:50:06,651 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732024206.6513162 2024-11-19 13:50:06,652 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732024206.6523244 2024-11-19 13:50:06,652 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732024206.6523244 2024-11-19 13:50:06,652 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732024206.6523244 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f2fbb5fdf80>' on 0 suites 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-19 13:50:06,652 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-19 13:50:06,653 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-19 13:50:06,653 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-19 13:50:06,653 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-19 13:50:06,653 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-19 13:50:06,653 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-19 13:50:06,653 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-19 13:50:06,653 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-19 13:50:06,653 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-release-asan: 0.0009610652923583984 2024-11-19 13:50:06,653 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-release-asan 2024-11-19 13:50:06,653 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732024206.653535 2024-11-19 13:50:06,653 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732024206.653535 2024-11-19 13:50:06,653 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732024206.653535 2024-11-19 13:50:06,653 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732024206.653696 2024-11-19 13:50:06,653 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732024206.653696 2024-11-19 13:50:06,653 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732024206.653696 2024-11-19 13:50:06,653 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732024206.6538372 2024-11-19 13:50:06,653 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732024206.6538372 2024-11-19 13:50:06,653 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732024206.6538372 2024-11-19 13:50:06,654 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-19 13:50:06,654 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 13:50:06,654 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-19 13:50:06,654 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-19 13:50:06,654 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732024206.6546926 2024-11-19 13:50:06,654 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732024206.6546926 2024-11-19 13:50:06,654 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732024206.6546926 2024-11-19 13:50:06,654 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732024206.6548395 2024-11-19 13:50:06,654 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732024206.6548395 2024-11-19 13:50:06,654 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732024206.6548395 2024-11-19 13:50:06,654 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732024206.6549737 2024-11-19 13:50:06,655 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732024206.6549737 2024-11-19 13:50:06,655 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732024206.6549737 2024-11-19 13:50:06,655 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.1086151599884033 2024-11-19 13:50:06,655 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732024206.6551526 2024-11-19 13:50:06,655 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732024206.6551526 2024-11-19 13:50:06,655 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732024206.6551526 2024-11-19 13:50:06,655 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 13:50:06,655 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00022172927856445312 2024-11-19 13:50:06,655 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732024206.6553671 2024-11-19 13:50:06,655 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732024206.6553671 2024-11-19 13:50:06,655 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732024206.6553671 2024-11-19 13:50:06,655 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.00018715858459472656 2024-11-19 13:50:06,655 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 13:50:06,655 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 13:50:06,655 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732024206.6557844 2024-11-19 13:50:06,655 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732024206.6557844 2024-11-19 13:50:06,655 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732024206.6557844 2024-11-19 13:50:06,655 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-19 13:50:06,655 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.106231689453125e-06 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732024206.6560023 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732024206.6560023 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732024206.6560023 2024-11-19 13:50:06,656 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006153583526611328 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732024206.6561723 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732024206.6561723 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732024206.6561723 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732024206.656284 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732024206.656284 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732024206.656284 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732024206.6564085 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732024206.6564085 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732024206.6564085 2024-11-19 13:50:06,656 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 13:50:06,656 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 13:50:06,656 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732024206.6566653 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732024206.6566653 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732024206.6566653 2024-11-19 13:50:06,656 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732024206.6567872 2024-11-19 13:50:06,656 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732024206.6567872 2024-11-19 13:50:06,656 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732024206.6567872 2024-11-19 13:50:06,657 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732024206.657057 2024-11-19 13:50:06,657 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732024206.657057 2024-11-19 13:50:06,657 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732024206.657057 2024-11-19 13:50:06,657 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732024206.6571927 2024-11-19 13:50:06,657 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732024206.6571927 2024-11-19 13:50:06,657 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732024206.6571927 2024-11-19 13:50:06,657 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0011985301971435547 2024-11-19 13:50:06,657 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732024206.6573703 2024-11-19 13:50:06,657 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732024206.6573703 2024-11-19 13:50:06,657 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732024206.6573703 2024-11-19 13:50:06,657 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732024206.6575768 2024-11-19 13:50:06,657 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732024206.6575768 2024-11-19 13:50:06,657 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732024206.6575768 2024-11-19 13:50:06,657 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732024206.6578884 2024-11-19 13:50:06,657 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732024206.6578884 2024-11-19 13:50:06,657 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732024206.6578884 2024-11-19 13:50:06,658 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732024206.6580384 2024-11-19 13:50:06,658 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732024206.6580384 2024-11-19 13:50:06,658 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732024206.6580384 2024-11-19 13:50:06,658 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732024206.6582134 2024-11-19 13:50:06,658 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732024206.6582134 2024-11-19 13:50:06,658 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732024206.6582134 2024-11-19 13:50:06,658 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732024206.6583362 2024-11-19 13:50:06,658 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732024206.6583362 2024-11-19 13:50:06,658 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732024206.6583362 2024-11-19 13:50:06,658 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.1422467231750488 2024-11-19 13:50:06,659 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732024206.6599674 2024-11-19 13:50:06,660 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732024206.6599674 2024-11-19 13:50:06,660 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732024206.6599674 2024-11-19 13:50:06,660 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-19 13:50:06,660 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-19 13:50:06,660 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-19 13:50:06,660 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732024206.6602604 2024-11-19 13:50:06,660 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732024206.6602604 2024-11-19 13:50:06,660 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732024206.6602604 2024-11-19 13:50:06,660 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732024206.6604016 2024-11-19 13:50:06,660 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732024206.6604016 2024-11-19 13:50:06,660 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732024206.6604016 2024-11-19 13:50:06,660 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-19 13:50:06,660 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732024206.6606014 2024-11-19 13:50:06,660 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732024206.6606014 2024-11-19 13:50:06,660 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732024206.6606014 2024-11-19 13:50:06,660 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-19 13:50:06,662 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-19 13:50:06,663 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-19 13:50:06,664 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-19 13:50:06,665 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-19 13:50:06,666 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-19 13:50:06,667 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-19 13:50:06,668 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-19 13:50:06,669 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-19 13:50:06,670 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-19 13:50:06,671 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-19 13:50:06,672 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-19 13:50:06,673 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-19 13:50:06,675 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-19 13:50:06,680 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/5f586c376a664f455134576533696c6f69764f74546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,680 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/6b59745a485a32516c59483048497458615f6834756700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,680 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/46775a713956734d56347574787255706b4469325f6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,680 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/386d716c70362d5969637470756a746c5f653239665100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,680 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,681 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing _Xl7jfOEQ4We3iloivOtTg => False 2024-11-19 13:50:06,681 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing kYtZHZ2QlYH0HItXa_h4ug => False 2024-11-19 13:50:06,681 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing FwZq9VsMV4utxrUpkDi2_g => False 2024-11-19 13:50:06,682 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 8mqlp6-Yictpujtl_e29fQ => False 2024-11-19 13:50:06,682 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/4a6a5962586b5366373571656b585352336e577a526700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,682 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 13:50:06,683 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing JjYbXkSf75qekXSR3nWzRg => False 2024-11-19 13:50:06,684 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/696d56314556676e41796b676762786e336b39384d4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,684 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing imV1EVgnAykggbxn3k98MA => False 2024-11-19 13:50:06,684 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,684 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,685 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 13:50:06,685 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 13:50:06,686 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/6f504c674170324b2d44755f644563476a5071374c5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,686 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing oPLgAp2K-Du_dEcGjPq7LQ => False 2024-11-19 13:50:06,687 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/7a657a78354339716a386d5a5f55414f674c36525a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,687 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing zezx5C9qj8mZ_UAOgL6RZA => False 2024-11-19 13:50:06,688 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/7a4f61657a72676b4c6b52507a496c773556742d635100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,688 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing zOaezrgkLkRPzIlw5Vt-cQ => False 2024-11-19 13:50:06,688 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/6f61444b4448767a4d6a45506359763271704646695100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,688 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing oaDKDHvzMjEPcYv2qpFFiQ => False 2024-11-19 13:50:06,689 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-19 13:50:06,689 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 13:50:06,692 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732024206.6927168 2024-11-19 13:50:06,692 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732024206.6927168 2024-11-19 13:50:06,692 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732024206.6927168 2024-11-19 13:50:06,692 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.034288883209228516 2024-11-19 13:50:06,693 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732024206.6930919 2024-11-19 13:50:06,693 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732024206.6930919 2024-11-19 13:50:06,693 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732024206.6930919 2024-11-19 13:50:06,693 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732024206.6932533 2024-11-19 13:50:06,693 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732024206.6932533 2024-11-19 13:50:06,693 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732024206.6932533 2024-11-19 13:50:06,693 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 13:50:06,693 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 13:50:06,693 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 13:50:06,693 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 13:50:06,693 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 13:50:06,693 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 13:50:06,693 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 13:50:06,694 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 13:50:06,694 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 13:50:06,694 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 13:50:06,694 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 13:50:06,694 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 13:50:06,694 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 13:50:06,694 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 13:50:06,694 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 13:50:06,695 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 13:50:06,695 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 13:50:06,695 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-19 13:50:06,695 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-19 13:50:06,695 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732024206.6951356 2024-11-19 13:50:06,695 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732024206.6951356 2024-11-19 13:50:06,695 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732024206.6951356 2024-11-19 13:50:06,695 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-19 13:50:06,706 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732024205.2928.352201uu/2928p6ro_bkg', '0'] 2024-11-19 13:50:06,706 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-19 13:50:06,726 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/r451/STAMP 2024-11-19 13:50:06,727 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.2901763916015625e-05 2024-11-19 13:50:06,727 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00012445449829101562 2024-11-19 13:50:06,727 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 5.5789947509765625e-05 2024-11-19 13:50:06,727 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 5.173683166503906e-05 2024-11-19 13:50:06,727 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-19 13:50:06,832 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-19 13:50:06,836 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid kYtZHZ2QlYH0HItXa_h4ug) address: (2509, 17239, 'unix:/home/runner/.ya/build/cache/7/psingebouLB.tmp') 2024-11-19 13:50:06,847 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/46775a713956734d56347574787255706b4469325f6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,848 DEBUG (urllib3.connectionpool) [Worker-009] http://cachesrv.internal:8081 "HEAD /ac/386d716c70362d5969637470756a746c5f653239665100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,848 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing FwZq9VsMV4utxrUpkDi2_g => False 2024-11-19 13:50:06,848 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/6b59745a485a32516c59483048497458615f6834756700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,849 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/696d56314556676e41796b676762786e336b39384d4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,849 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 8mqlp6-Yictpujtl_e29fQ created (provider=None) 2024-11-19 13:50:06,849 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-009] Bazel-remote Probing 8mqlp6-Yictpujtl_e29fQ => False 2024-11-19 13:50:06,850 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-007] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-19 13:50:06,850 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/4a6a5962586b5366373571656b585352336e577a526700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,851 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-023] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-19 13:50:06,851 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "HEAD /ac/7a657a78354339716a386d5a5f55414f674c36525a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,851 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing kYtZHZ2QlYH0HItXa_h4ug => False 2024-11-19 13:50:06,851 DEBUG (urllib3.connectionpool) [Worker-017] http://cachesrv.internal:8081 "HEAD /ac/6f504c674170324b2d44755f644563476a5071374c5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,852 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing imV1EVgnAykggbxn3k98MA => False 2024-11-19 13:50:06,852 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for _Xl7jfOEQ4We3iloivOtTg created (provider=None) 2024-11-19 13:50:06,852 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "HEAD /ac/7a4f61657a72676b4c6b52507a496c773556742d635100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,853 DEBUG (yalibrary.toolscache) [Worker-007] Tools cache attempt: (request for resource 7336031045) 2024-11-19 13:50:06,853 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing JjYbXkSf75qekXSR3nWzRg => False 2024-11-19 13:50:06,853 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/5f586c376a664f455134576533696c6f69764f74546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,853 DEBUG (yalibrary.toolscache) [Worker-023] Tools cache attempt: (request for resource 7458630270) 2024-11-19 13:50:06,854 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-016] Bazel-remote Probing zezx5C9qj8mZ_UAOgL6RZA => False 2024-11-19 13:50:06,855 DEBUG (urllib3.connectionpool) [Worker-019] http://cachesrv.internal:8081 "HEAD /ac/6f61444b4448767a4d6a45506359763271704646695100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:06,856 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-017] Bazel-remote Probing oPLgAp2K-Du_dEcGjPq7LQ => False 2024-11-19 13:50:06,856 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for kYtZHZ2QlYH0HItXa_h4ug created (provider=None) 2024-11-19 13:50:06,857 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-018] Bazel-remote Probing zOaezrgkLkRPzIlw5Vt-cQ => False 2024-11-19 13:50:06,858 DEBUG (yalibrary.toolscache) [Worker-007] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 13:50:06,859 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing _Xl7jfOEQ4We3iloivOtTg => False 2024-11-19 13:50:06,860 DEBUG (yalibrary.toolscache) [Worker-023] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 13:50:06,860 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-019] Bazel-remote Probing oaDKDHvzMjEPcYv2qpFFiQ => False 2024-11-19 13:50:06,861 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for FwZq9VsMV4utxrUpkDi2_g created (provider=None) 2024-11-19 13:50:06,864 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,865 DEBUG (yalibrary.fetcher.cache_helper) [Worker-023] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 13:50:06,866 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 13:50:06,866 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for JjYbXkSf75qekXSR3nWzRg created (provider=None) 2024-11-19 13:50:06,867 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 13:50:06,867 DEBUG (library.python.filelock) [Worker-023] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 13:50:06,867 DEBUG (library.python.filelock) [Worker-007] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 13:50:06,867 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for imV1EVgnAykggbxn3k98MA created (provider=None) 2024-11-19 13:50:06,868 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,868 DEBUG (yalibrary.fetcher.cache_helper) [Worker-023] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 13:50:06,868 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 13:50:06,868 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for oPLgAp2K-Du_dEcGjPq7LQ created (provider=None) 2024-11-19 13:50:06,869 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 13:50:06,869 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for zezx5C9qj8mZ_UAOgL6RZA created (provider=None) 2024-11-19 13:50:06,869 DEBUG (exts.http_client) [Worker-023] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.terjo7vd.661f85ud.part, expect md5- 2024-11-19 13:50:06,870 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for zOaezrgkLkRPzIlw5Vt-cQ created (provider=None) 2024-11-19 13:50:06,870 DEBUG (exts.http_client) [Worker-007] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.q8loywmw.tn999n68.part, expect md5- 2024-11-19 13:50:06,871 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for oaDKDHvzMjEPcYv2qpFFiQ created (provider=None) 2024-11-19 13:50:06,871 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-19 13:50:06,877 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:06,877 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-19 13:50:06,878 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-19 13:50:06,883 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 13:50:06,884 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-19 13:50:06,890 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-19 13:50:06,891 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-19 13:50:06,892 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-19 13:50:06,897 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-19 13:50:06,898 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-19 13:50:06,899 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-19 13:50:06,904 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-19 13:50:06,905 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-19 13:50:06,905 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-19 13:50:06,906 DEBUG (yalibrary.toolscache) [Worker-011] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-19 13:50:06,906 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-19 13:50:06,914 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-19 13:50:06,915 DEBUG (yalibrary.toolscache) [Worker-015] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-19 13:50:06,943 DEBUG (exts.http_client) [Worker-007] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Tue, 19 Nov 2024 13:50:06 GMT Content-Type: application/octet-stream Content-Length: 12509507 Connection: close Accept-Ranges: bytes Etag: "2e88d3d4d49302ffa7daf9d507d47587-2" Last-Modified: Thu, 24 Oct 2024 17:39:31 GMT X-Amz-Request-Id: 0c3cb286abeb3cdd NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 13:50:06,946 DEBUG (exts.http_client) [Worker-023] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Tue, 19 Nov 2024 13:50:06 GMT Content-Type: application/octet-stream Content-Length: 411596800 Connection: close Accept-Ranges: bytes Etag: "6725536bb72acadd7519eafb09702e00-50" Last-Modified: Thu, 14 Nov 2024 13:02:29 GMT X-Amz-Request-Id: 9c01f40cb78ad277 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 13:50:07,048 DEBUG (exts.http_client) [Worker-007] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.q8loywmw, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.177365 2024-11-19 13:50:07,048 DEBUG (yalibrary.fetcher.common) [Worker-007] extract /home/runner/.ya/tools/v4/7336031045/resource.q8loywmw to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-19 13:50:07,200 DEBUG (library.python.filelock) [Worker-007] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 13:50:07,202 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (get uid df3222d93c490833a146254915e62e57) 2024-11-19 13:50:07,205 DEBUG (yalibrary.toolscache) [Worker-067] AC cache attempt: (get uid f86f661c845b20681c5e2ca83931fd8c) 2024-11-19 13:50:07,213 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/d5d2d0c5e00d5452cfca91ffd9c697a6454d8ca214ed70060c745d7b22f4e511 HTTP/1.1" 404 0 2024-11-19 13:50:07,213 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing df3222d93c490833a146254915e62e57 => False 2024-11-19 13:50:07,213 DEBUG (root) [Worker-007] Run node kYtZHZ2QlYH0HItXa_h4ug in build root /home/runner/.ya/build/build_root/r451/00000f 2024-11-19 13:50:07,214 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "HEAD /ac/9bb1c46bc02b348b249f9012067272e634944803f931e58f672dc184405a3911 HTTP/1.1" 404 0 2024-11-19 13:50:07,214 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-067] Bazel-remote Probing f86f661c845b20681c5e2ca83931fd8c => False 2024-11-19 13:50:07,215 DEBUG (root) [Worker-067] Run node zOaezrgkLkRPzIlw5Vt-cQ in build root /home/runner/.ya/build/build_root/r451/000016 2024-11-19 13:50:07,550 DEBUG (yalibrary.toolscache) [Worker-074] AC cache attempt: (put uid kYtZHZ2QlYH0HItXa_h4ug) 2024-11-19 13:50:07,551 DEBUG (yalibrary.runner.tasks.result) [Worker-007] Result node kYtZHZ2QlYH0HItXa_h4ug processed successfully: True 2024-11-19 13:50:07,551 DEBUG (yalibrary.toolscache) [Worker-074] AC cache attempt: (put uid df3222d93c490833a146254915e62e57) 2024-11-19 13:50:07,556 DEBUG (urllib3.connectionpool) [Worker-071] http://cachesrv.internal:8081 "HEAD /ac/6b59745a485a32516c59483048497458615f6834756700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:07,556 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-071] Bazel-remote Probing kYtZHZ2QlYH0HItXa_h4ug => False 2024-11-19 13:50:07,556 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-071] Put ydb/docs/ru/core/docs-ru-core.docslib(kYtZHZ2QlYH0HItXa_h4ug) to Bazel-remote 2024-11-19 13:50:07,566 DEBUG (urllib3.connectionpool) [Worker-071] http://cachesrv.internal:8081 "PUT /cas/dc719aa21e7f7156c3c5b513df866eb2ae10c9764c37b6b9e6d491c12d9f73ea HTTP/1.1" 200 0 2024-11-19 13:50:07,575 DEBUG (urllib3.connectionpool) [Worker-071] http://cachesrv.internal:8081 "PUT /cas/057dca556bab58eab7b70bd10f8ef2b8bc86ced54cce27a4eabdae6eccfa588d HTTP/1.1" 200 0 2024-11-19 13:50:07,768 DEBUG (yalibrary.runner.tasks.result) [Worker-083] Result node zOaezrgkLkRPzIlw5Vt-cQ processed successfully: True 2024-11-19 13:50:07,768 DEBUG (yalibrary.toolscache) [Worker-085] AC cache attempt: (put uid zOaezrgkLkRPzIlw5Vt-cQ) 2024-11-19 13:50:07,770 DEBUG (yalibrary.toolscache) [Worker-085] AC cache attempt: (put uid f86f661c845b20681c5e2ca83931fd8c) 2024-11-19 13:50:07,774 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "HEAD /ac/7a4f61657a72676b4c6b52507a496c773556742d635100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:07,775 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-067] Bazel-remote Probing zOaezrgkLkRPzIlw5Vt-cQ => False 2024-11-19 13:50:07,775 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-067] Put ydb/docs/__docs_internal.docslib.fake(zOaezrgkLkRPzIlw5Vt-cQ) to Bazel-remote 2024-11-19 13:50:07,785 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "PUT /cas/12220972e77029b3968dd41589032f8c30b01d347c879e54e7cd892e0a8a97ce HTTP/1.1" 200 0 2024-11-19 13:50:07,794 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "PUT /cas/7daa21365fe65d71403099255730e2ca9ebecbb97d9fbad88057ce574c79a670 HTTP/1.1" 200 0 2024-11-19 13:50:07,885 DEBUG (urllib3.connectionpool) [Worker-071] http://cachesrv.internal:8081 "PUT /cas/39ceee5949464e9f38e5cd9e07f61cb1b77e362e2053f267b3e8f6805475e9c7 HTTP/1.1" 200 0 2024-11-19 13:50:07,894 DEBUG (urllib3.connectionpool) [Worker-071] http://cachesrv.internal:8081 "PUT /ac/6b59745a485a32516c59483048497458615f6834756700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:07,894 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-071] Put ydb/docs/ru/core/docs-ru-core.docslib(kYtZHZ2QlYH0HItXa_h4ug) size=29634833 to Bazel-remote completed 2024-11-19 13:50:08,293 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "PUT /cas/7e54bae251edf023c2d5f0019cd97f7f2edb11e508fd31b5cdda3b3ce2c40608 HTTP/1.1" 200 0 2024-11-19 13:50:08,302 DEBUG (urllib3.connectionpool) [Worker-067] http://cachesrv.internal:8081 "PUT /ac/7a4f61657a72676b4c6b52507a496c773556742d635100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:08,303 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-067] Put ydb/docs/__docs_internal.docslib.fake(zOaezrgkLkRPzIlw5Vt-cQ) size=51589393 to Bazel-remote completed 2024-11-19 13:50:12,956 DEBUG (exts.http_client) [Worker-023] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.terjo7vd, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=6.086468 2024-11-19 13:50:12,957 DEBUG (yalibrary.fetcher.common) [Worker-023] extract /home/runner/.ya/tools/v4/7458630270/resource.terjo7vd to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-19 13:50:13,540 DEBUG (library.python.filelock) [Worker-023] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 13:50:13,541 DEBUG (yalibrary.toolscache) [Worker-023] AC cache attempt: (get uid 2c2b71947f0232b43a4536563f43b8fe) 2024-11-19 13:50:13,542 DEBUG (yalibrary.toolscache) [Worker-097] AC cache attempt: (get uid 0ee8e971e680134f6d2a55604da8aa1b) 2024-11-19 13:50:13,542 DEBUG (yalibrary.toolscache) [Worker-096] AC cache attempt: (get uid 2d378c5cb4ef0ea6b76c8a41253050f8) 2024-11-19 13:50:13,543 DEBUG (yalibrary.toolscache) [Worker-098] AC cache attempt: (get uid 9d838180eab164bbe28f471a6b28c426) 2024-11-19 13:50:13,551 DEBUG (urllib3.connectionpool) [Worker-023] http://cachesrv.internal:8081 "HEAD /ac/5cc69e5de6ff0f5b793ab519c127d90deab3c10a176101864d342f4ae9108211 HTTP/1.1" 404 0 2024-11-19 13:50:13,551 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-023] Bazel-remote Probing 2c2b71947f0232b43a4536563f43b8fe => False 2024-11-19 13:50:13,551 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "HEAD /ac/283aa37372c83e70016a9684716ab3eb1b40400b7a78db03d213e8400c14bb11 HTTP/1.1" 404 0 2024-11-19 13:50:13,552 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-097] Bazel-remote Probing 0ee8e971e680134f6d2a55604da8aa1b => False 2024-11-19 13:50:13,552 DEBUG (urllib3.connectionpool) [Worker-096] http://cachesrv.internal:8081 "HEAD /ac/595f0526122a24ab37622b7b5750c8bdcfeac5c854907af389d108b69289fd11 HTTP/1.1" 404 0 2024-11-19 13:50:13,552 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-096] Bazel-remote Probing 2d378c5cb4ef0ea6b76c8a41253050f8 => False 2024-11-19 13:50:13,553 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "HEAD /ac/7a8810a8c70e4d6b0211c9646fecf10ffed7cb9fe6817cef8324c3c049908e11 HTTP/1.1" 404 0 2024-11-19 13:50:13,553 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Bazel-remote Probing 9d838180eab164bbe28f471a6b28c426 => False 2024-11-19 13:50:13,555 DEBUG (root) [Worker-023] Run node imV1EVgnAykggbxn3k98MA in build root /home/runner/.ya/build/build_root/r451/000010 2024-11-19 13:50:13,558 DEBUG (root) [Worker-096] Run node JjYbXkSf75qekXSR3nWzRg in build root /home/runner/.ya/build/build_root/r451/000012 2024-11-19 13:50:13,587 DEBUG (root) [Worker-098] Run node _Xl7jfOEQ4We3iloivOtTg in build root /home/runner/.ya/build/build_root/r451/000017 2024-11-19 13:50:13,606 DEBUG (root) [Worker-097] Run node zezx5C9qj8mZ_UAOgL6RZA in build root /home/runner/.ya/build/build_root/r451/000013 2024-11-19 13:50:16,887 DEBUG (yalibrary.toolscache) [Worker-106] AC cache attempt: (put uid imV1EVgnAykggbxn3k98MA) 2024-11-19 13:50:16,888 DEBUG (yalibrary.toolscache) [Worker-102] AC cache attempt: (get uid 4220b7c0f85cf4e8d32ec8c734ae891e) 2024-11-19 13:50:16,889 DEBUG (yalibrary.runner.tasks.result) [Worker-023] Result node imV1EVgnAykggbxn3k98MA processed successfully: True 2024-11-19 13:50:16,889 DEBUG (yalibrary.toolscache) [Worker-106] AC cache attempt: (put uid 2c2b71947f0232b43a4536563f43b8fe) 2024-11-19 13:50:16,894 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "HEAD /ac/696d56314556676e41796b676762786e336b39384d4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:16,894 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Bazel-remote Probing imV1EVgnAykggbxn3k98MA => False 2024-11-19 13:50:16,895 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(imV1EVgnAykggbxn3k98MA) to Bazel-remote 2024-11-19 13:50:16,896 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "HEAD /ac/1611407c8d21b40f88bcccbae90352cc43bc84eda28b33a88d748d5f3f2f7d11 HTTP/1.1" 404 0 2024-11-19 13:50:16,897 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-102] Bazel-remote Probing 4220b7c0f85cf4e8d32ec8c734ae891e => False 2024-11-19 13:50:16,897 DEBUG (root) [Worker-102] Run node oaDKDHvzMjEPcYv2qpFFiQ in build root /home/runner/.ya/build/build_root/r451/000018 2024-11-19 13:50:16,904 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/870f9c9269e13caa19a6ae315c89668db800c0403a31fe298e2fe64440edfd00 HTTP/1.1" 200 0 2024-11-19 13:50:16,913 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/7cd6cbe40b24b21d34ee8e4f8b074ed2068f4b73e60c64407cb3d3a771760537 HTTP/1.1" 200 0 2024-11-19 13:50:16,960 DEBUG (yalibrary.toolscache) [Worker-122] AC cache attempt: (put uid JjYbXkSf75qekXSR3nWzRg) 2024-11-19 13:50:16,961 DEBUG (yalibrary.toolscache) [Worker-118] AC cache attempt: (get uid e0599331191beee3b1fe88d5e8a5dc88) 2024-11-19 13:50:16,961 DEBUG (yalibrary.runner.tasks.result) [Worker-117] Result node JjYbXkSf75qekXSR3nWzRg processed successfully: True 2024-11-19 13:50:16,962 DEBUG (yalibrary.toolscache) [Worker-122] AC cache attempt: (put uid 2d378c5cb4ef0ea6b76c8a41253050f8) 2024-11-19 13:50:16,966 DEBUG (urllib3.connectionpool) [Worker-116] http://cachesrv.internal:8081 "HEAD /ac/4a6a5962586b5366373571656b585352336e577a526700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:16,966 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-116] Bazel-remote Probing JjYbXkSf75qekXSR3nWzRg => False 2024-11-19 13:50:16,967 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-116] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(JjYbXkSf75qekXSR3nWzRg) to Bazel-remote 2024-11-19 13:50:16,968 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/10cacf875d073e8b0e2411baeab85ba64091fadf36007677e82d0e4afb741d8e HTTP/1.1" 200 0 2024-11-19 13:50:16,969 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "HEAD /ac/d675413e43c8c3ccd8746ead25e7bd1fe0210301e986ad63379555e2dee0ef11 HTTP/1.1" 404 0 2024-11-19 13:50:16,969 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Bazel-remote Probing e0599331191beee3b1fe88d5e8a5dc88 => False 2024-11-19 13:50:16,969 DEBUG (root) [Worker-118] Run node FwZq9VsMV4utxrUpkDi2_g in build root /home/runner/.ya/build/build_root/r451/00000d 2024-11-19 13:50:16,976 DEBUG (urllib3.connectionpool) [Worker-116] http://cachesrv.internal:8081 "PUT /cas/46f57e262d0b5a2992d2314eca1c51aaec586196408ea4f8496a841e6336d3c1 HTTP/1.1" 200 0 2024-11-19 13:50:16,977 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /ac/696d56314556676e41796b676762786e336b39384d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:16,977 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(imV1EVgnAykggbxn3k98MA) size=1092374 to Bazel-remote completed 2024-11-19 13:50:16,985 DEBUG (urllib3.connectionpool) [Worker-116] http://cachesrv.internal:8081 "PUT /cas/81f7eb87cefa8d9a75cbd7dfd81a36da60d468b30ef63f93eea3738b6768fed6 HTTP/1.1" 200 0 2024-11-19 13:50:16,994 DEBUG (yalibrary.toolscache) [Worker-133] AC cache attempt: (put uid _Xl7jfOEQ4We3iloivOtTg) 2024-11-19 13:50:16,995 DEBUG (yalibrary.toolscache) [Worker-134] AC cache attempt: (get uid 2afed5e08df29a09ca0befa4950ec35d) 2024-11-19 13:50:16,996 DEBUG (yalibrary.runner.tasks.result) [Worker-131] Result node _Xl7jfOEQ4We3iloivOtTg processed successfully: True 2024-11-19 13:50:16,997 DEBUG (yalibrary.toolscache) [Worker-133] AC cache attempt: (put uid 9d838180eab164bbe28f471a6b28c426) 2024-11-19 13:50:17,000 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "HEAD /ac/5f586c376a664f455134576533696c6f69764f74546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:17,001 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Bazel-remote Probing _Xl7jfOEQ4We3iloivOtTg => False 2024-11-19 13:50:17,001 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(_Xl7jfOEQ4We3iloivOtTg) to Bazel-remote 2024-11-19 13:50:17,004 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "HEAD /ac/302caceb445c81c006ecb48fd2dc8cff864c7ceb03354080b15cc14055cf1311 HTTP/1.1" 404 0 2024-11-19 13:50:17,004 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-134] Bazel-remote Probing 2afed5e08df29a09ca0befa4950ec35d => False 2024-11-19 13:50:17,004 DEBUG (root) [Worker-134] Run node 8mqlp6-Yictpujtl_e29fQ in build root /home/runner/.ya/build/build_root/r451/00000e 2024-11-19 13:50:17,011 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/71ed7787c9c32cab2cac156decd6c54db4e1804533d7b34067085d6bc3821695 HTTP/1.1" 200 0 2024-11-19 13:50:17,021 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/f31e50ee959ac4bf96a49e7abb7be1c0af4ca72043b0163a0f1127bfd26ed926 HTTP/1.1" 200 0 2024-11-19 13:50:17,039 DEBUG (urllib3.connectionpool) [Worker-116] http://cachesrv.internal:8081 "PUT /cas/fddb27399a982f680d48687e70b500ce9bcd39311038298b3ec311dfe162221d HTTP/1.1" 200 0 2024-11-19 13:50:17,045 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/fb7dcf5bbc2bf9221826178fc3bc4b89ef9260d12ed2245eb53e19f95d03fc12 HTTP/1.1" 200 0 2024-11-19 13:50:17,048 DEBUG (urllib3.connectionpool) [Worker-116] http://cachesrv.internal:8081 "PUT /ac/4a6a5962586b5366373571656b585352336e577a526700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:17,048 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-116] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(JjYbXkSf75qekXSR3nWzRg) size=1092378 to Bazel-remote completed 2024-11-19 13:50:17,054 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /ac/5f586c376a664f455134576533696c6f69764f74546700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:17,054 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(_Xl7jfOEQ4We3iloivOtTg) size=1092345 to Bazel-remote completed 2024-11-19 13:50:21,861 DEBUG (yalibrary.toolscache) [Worker-012] AC cache attempt: (put uid FwZq9VsMV4utxrUpkDi2_g) 2024-11-19 13:50:21,862 DEBUG (yalibrary.runner.tasks.result) [Worker-008] Result node FwZq9VsMV4utxrUpkDi2_g processed successfully: True 2024-11-19 13:50:21,863 DEBUG (yalibrary.toolscache) [Worker-012] AC cache attempt: (put uid e0599331191beee3b1fe88d5e8a5dc88) 2024-11-19 13:50:21,868 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "HEAD /ac/46775a713956734d56347574787255706b4469325f6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:21,868 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Bazel-remote Probing FwZq9VsMV4utxrUpkDi2_g => False 2024-11-19 13:50:21,868 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(FwZq9VsMV4utxrUpkDi2_g) to Bazel-remote 2024-11-19 13:50:21,878 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/2ce92a85660a3553b3372ad168a2b8a66fe2313be5f4b3804ef648bfd64f2ea5 HTTP/1.1" 200 0 2024-11-19 13:50:21,887 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/7e6eeab4a44ef5af6fbb047878d6c14089edb323620c2f71e189f5e79d6f1cee HTTP/1.1" 200 0 2024-11-19 13:50:21,960 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/4d710dd9a329e938bace6a4f3b7575ab4e947e5dc3292fc16e1d78ef8bfbbeae HTTP/1.1" 200 0 2024-11-19 13:50:21,969 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /ac/46775a713956734d56347574787255706b4469325f6700000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:21,970 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(FwZq9VsMV4utxrUpkDi2_g) size=2779990 to Bazel-remote completed 2024-11-19 13:50:22,072 DEBUG (yalibrary.toolscache) [Worker-028] AC cache attempt: (put uid oaDKDHvzMjEPcYv2qpFFiQ) 2024-11-19 13:50:22,073 DEBUG (yalibrary.runner.tasks.result) [Worker-005] Result node oaDKDHvzMjEPcYv2qpFFiQ processed successfully: True 2024-11-19 13:50:22,074 DEBUG (yalibrary.toolscache) [Worker-028] AC cache attempt: (put uid 4220b7c0f85cf4e8d32ec8c734ae891e) 2024-11-19 13:50:22,079 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "HEAD /ac/6f61444b4448767a4d6a45506359763271704646695100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:22,079 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-102] Bazel-remote Probing oaDKDHvzMjEPcYv2qpFFiQ => False 2024-11-19 13:50:22,079 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-102] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(oaDKDHvzMjEPcYv2qpFFiQ) to Bazel-remote 2024-11-19 13:50:22,089 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "PUT /cas/694698785aa3a0a988fe4305561c91db6e3f423cb181d50ad1e0b81012458486 HTTP/1.1" 200 0 2024-11-19 13:50:22,098 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "PUT /cas/387b9e41fdb27bd8504c05a307a0010a1e0ecaf60d22e463c6ebbce7fb4c6a75 HTTP/1.1" 200 0 2024-11-19 13:50:22,127 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "PUT /cas/0e2b4c3e901335f8300ad1a60e27543cb1e66c5fba0f2eaf12b0de82082f7959 HTTP/1.1" 200 0 2024-11-19 13:50:22,135 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "PUT /ac/6f61444b4448767a4d6a45506359763271704646695100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:22,136 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-102] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(oaDKDHvzMjEPcYv2qpFFiQ) size=2779980 to Bazel-remote completed 2024-11-19 13:50:22,173 DEBUG (yalibrary.toolscache) [Worker-040] AC cache attempt: (put uid 8mqlp6-Yictpujtl_e29fQ) 2024-11-19 13:50:22,175 DEBUG (yalibrary.runner.tasks.result) [Worker-038] Result node 8mqlp6-Yictpujtl_e29fQ processed successfully: True 2024-11-19 13:50:22,175 DEBUG (yalibrary.toolscache) [Worker-040] AC cache attempt: (put uid 2afed5e08df29a09ca0befa4950ec35d) 2024-11-19 13:50:22,181 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "HEAD /ac/386d716c70362d5969637470756a746c5f653239665100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:22,181 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-134] Bazel-remote Probing 8mqlp6-Yictpujtl_e29fQ => False 2024-11-19 13:50:22,181 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-134] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(8mqlp6-Yictpujtl_e29fQ) to Bazel-remote 2024-11-19 13:50:22,191 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "PUT /cas/92df6cd565e2371822d4e7acacff7e01875b9d8aee377437c65465815fab41f4 HTTP/1.1" 200 0 2024-11-19 13:50:22,200 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "PUT /cas/9fb99588788f1f9660d5822a1e590d9f9dc757c108a80964e7833904e461a159 HTTP/1.1" 200 0 2024-11-19 13:50:22,230 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "PUT /cas/0004e90c9ccaf4bb54f07ada28f47fa3b54132d8b146b904ce67f2bf5d030a09 HTTP/1.1" 200 0 2024-11-19 13:50:22,239 DEBUG (urllib3.connectionpool) [Worker-134] http://cachesrv.internal:8081 "PUT /ac/386d716c70362d5969637470756a746c5f653239665100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:22,239 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-134] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(8mqlp6-Yictpujtl_e29fQ) size=2780007 to Bazel-remote completed 2024-11-19 13:50:32,699 DEBUG (yalibrary.toolscache) [Worker-052] AC cache attempt: (put uid zezx5C9qj8mZ_UAOgL6RZA) 2024-11-19 13:50:32,700 DEBUG (yalibrary.toolscache) [Worker-050] AC cache attempt: (get uid 7d32d543db612ddc4bb766452068b931) 2024-11-19 13:50:32,700 DEBUG (yalibrary.runner.tasks.result) [Worker-048] Result node zezx5C9qj8mZ_UAOgL6RZA processed successfully: True 2024-11-19 13:50:32,702 DEBUG (yalibrary.toolscache) [Worker-052] AC cache attempt: (put uid 0ee8e971e680134f6d2a55604da8aa1b) 2024-11-19 13:50:32,707 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "HEAD /ac/7a657a78354339716a386d5a5f55414f674c36525a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:50:32,707 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-097] Bazel-remote Probing zezx5C9qj8mZ_UAOgL6RZA => False 2024-11-19 13:50:32,707 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-097] Put ydb/docs/preprocessed.tar.gz(zezx5C9qj8mZ_UAOgL6RZA) to Bazel-remote 2024-11-19 13:50:32,708 DEBUG (urllib3.connectionpool) [Worker-050] http://cachesrv.internal:8081 "HEAD /ac/7591bb260a9b4a453642725d6b38aa335b57978900405a4ea7cfd5e848331411 HTTP/1.1" 404 0 2024-11-19 13:50:32,708 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-050] Bazel-remote Probing 7d32d543db612ddc4bb766452068b931 => False 2024-11-19 13:50:32,709 DEBUG (root) [Worker-050] Run node oPLgAp2K-Du_dEcGjPq7LQ in build root /home/runner/.ya/build/build_root/r451/000015 2024-11-19 13:50:32,717 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "PUT /cas/ad0f9d97109cbde40615a8ea0fe642b6230d6692e2fcca8a3358d7fdec9ae823 HTTP/1.1" 200 0 2024-11-19 13:50:32,726 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "PUT /cas/cb76aa537db37b99e230bc54e7848b837f40177b095c1d6bb4e9deed8b09780a HTTP/1.1" 200 0 2024-11-19 13:50:33,046 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "PUT /cas/d965e15d29db059115db67d0c2ee754e9e9e74df0da85ca9e751a09ab22599b6 HTTP/1.1" 200 0 2024-11-19 13:50:33,058 DEBUG (urllib3.connectionpool) [Worker-097] http://cachesrv.internal:8081 "PUT /ac/7a657a78354339716a386d5a5f55414f674c36525a4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:50:33,058 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-097] Put ydb/docs/preprocessed.tar.gz(zezx5C9qj8mZ_UAOgL6RZA) size=36209107 to Bazel-remote completed 2024-11-19 13:51:17,557 DEBUG (yalibrary.toolscache) [Worker-058] AC cache attempt: (put uid oPLgAp2K-Du_dEcGjPq7LQ) 2024-11-19 13:51:17,557 DEBUG (yalibrary.runner.tasks.result) [Worker-050] Result node oPLgAp2K-Du_dEcGjPq7LQ processed successfully: True 2024-11-19 13:51:17,558 DEBUG (yalibrary.toolscache) [Worker-058] AC cache attempt: (put uid 7d32d543db612ddc4bb766452068b931) 2024-11-19 13:51:17,563 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/6f504c674170324b2d44755f644563476a5071374c5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 13:51:17,563 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing oPLgAp2K-Du_dEcGjPq7LQ => False 2024-11-19 13:51:17,564 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Put ydb/docs/ydb-docs.tar.gz(oPLgAp2K-Du_dEcGjPq7LQ) to Bazel-remote 2024-11-19 13:51:17,575 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "PUT /cas/fe1e884c15abfaccc6bfce8e68235daae4cc8bc7d8654bb2c14cc3a8a78e1f7d HTTP/1.1" 200 0 2024-11-19 13:51:17,586 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "PUT /cas/d4a426d8230bbab69b04fc78b4040a1c2454daffe0d66f6f64f5f54bd8633d91 HTTP/1.1" 200 0 2024-11-19 13:51:17,935 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "PUT /cas/eabe99ff70911315b6a0c9e785ed73c1e265e4919f665d46794eaafdc15107a3 HTTP/1.1" 200 0 2024-11-19 13:51:17,944 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "PUT /ac/6f504c674170324b2d44755f644563476a5071374c5100000000000000000001 HTTP/1.1" 200 0 2024-11-19 13:51:17,944 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Put ydb/docs/ydb-docs.tar.gz(oPLgAp2K-Du_dEcGjPq7LQ) size=39678549 to Bazel-remote completed 2024-11-19 13:51:18,005 DEBUG (yalibrary.runner.build_root) [Worker-076] Build root /home/runner/.ya/build/build_root/r451 created=25 left=12 2024-11-19 13:51:18,005 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/r451/STAMP 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(imV1EVgnAykggbxn3k98MA$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(JjYbXkSf75qekXSR3nWzRg$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query.png COPY Copying file ydb/d ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(_Xl7jfOEQ4We3iloivOtTg$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(FwZq9VsMV4utxrUpkDi2_g$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(oaDKDHvzMjEPcYv2qpFFiQ$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(8mqlp6-Yictpujtl_e29fQ$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/tests-build-default.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(zezx5C9qj8mZ_UAOgL6RZA$(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/gtm.js COPY Copying file _assets/logo.svg COPY Copying file _assets/tags.css COPY Copying file _assets/ydb-promo ... d: ru/changelog-server.md INFO Processing finished: ru/changelog-cli.md INFO Processing finished: ru/security-changelog.md COPY Copying file _assets/tags.css 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(oPLgAp2K-Du_dEcGjPq7LQ$(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... opying file search/langs/tr.js COPY Copying file search/langs/vi.js COPY Copying file vendor.css COPY Copying file vendor.js COPY Copying file vendor.rtl.css 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.00413703918457,"qty":1},"prepare_all_nodes":{"sum":0.038860559463501,"qty":1},"compact_cache":{"sum":0.014424800872803,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.311121940612793,"qty":13},"prepare[DO]":{"sum":0.002588033676147,"qty":3},"put_in_cache[DO]":{"sum":0.035991191864014,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.349911689758301,"qty":1},"DO":{"sum":90.398477792739868,"qty":10},"result[DO]":{"sum":0.018023252487183,"qty":10},"write_through_caches[DO]":{"sum":0.081609487533569,"qty":10},"put_in_dist_cache[DO]":{"sum":2.320766925811768,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":6.689544677734375,"qty":1},"clean_build_root":{"sum":0.000191688537598,"qty":1}},"all":{"qty":75,"sum":100.265649080276489},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732024206.851058721542358,1732024213.540603399276733],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":71.182103633880615,"build_type":"release","flags":{"OPENSOURCE":"yes","USE_PREBUILT_TOOLS":"no","APPLE_SDK_LOCAL":"yes","USE_CLANG_CL":"yes","USE_AIO":"static","USE_ICONV":"static","USE_IDN":"static","CFLAGS":"-fno-omit-frame-pointer","USE_EAT_MY_DATA":"yes","DEBUGINFO_LINES_ONLY":"yes","CONSISTENT_DEBUG":"yes","TESTS_REQUESTED":"yes","DISABLE_FLAKE8_MIGRATIONS":"yes"},"rel_targets":["."],"threads":64} 2024-11-19 13:51:18,015 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-19 13:51:18,015 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732024278.0159633 2024-11-19 13:51:18,016 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732024278.0159633 2024-11-19 13:51:18,016 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732024278.0159633 2024-11-19 13:51:18,016 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732024278.0163288 2024-11-19 13:51:18,016 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732024278.0163288 2024-11-19 13:51:18,016 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732024278.0163288 2024-11-19 13:51:18,030 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-19 13:51:18,030 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-19 13:51:18,030 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,030 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,030 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-19 13:51:18,030 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-19 13:51:18,030 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-19 13:51:18,032 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-19 13:51:18,039 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,039 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,040 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,040 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 13:51:18,040 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732024278.040575 2024-11-19 13:51:18,040 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732024278.040575 2024-11-19 13:51:18,040 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732024278.040575 2024-11-19 13:51:18,040 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-19 13:51:18,041 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-19 13:51:18,041 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-19 13:51:18,042 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168759676 2024-11-19 13:51:18,042 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732024278.0423684 2024-11-19 13:51:18,042 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732024278.0423684 2024-11-19 13:51:18,042 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732024278.0423684 2024-11-19 13:51:18,042 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-19 13:51:18,042 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-19 13:51:18,043 DEBUG (build.stat.statistics) [MainThread] Run tasks 13: 13 cached tasks (0 cache(s) resolved by dynamic uids), 10 not cached, 0 failed 2024-11-19 13:51:18,043 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-19 13:51:18,043 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",543,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",19072,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",44826,"local"]] 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 70322 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 64441 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 64441 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-19 13:51:18,044 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 44826 2024-11-19 13:51:18,045 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-19 13:51:18,045 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-19 13:51:18,045 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-19 13:51:18,045 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-19 13:51:18,045 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 90116 2024-11-19 13:51:18,046 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732024278.0463867 2024-11-19 13:51:18,046 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732024278.0463867 2024-11-19 13:51:18,046 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732024278.0463867 2024-11-19 13:51:18,047 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732024278.0469887 2024-11-19 13:51:18,047 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732024278.0469887 2024-11-19 13:51:18,047 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732024278.0469887 2024-11-19 13:51:18,047 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 13:51:18,047 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-19 13:51:18,047 DEBUG (StoredReport) [MainThread] Finish report 2024-11-19 13:51:18,047 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 8.082389831542969e-05}, 'BuildResultsListener': {'duration_sec': 0.001314401626586914}} 2024-11-19 13:51:18,047 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-19 13:51:18,048 INFO (build.reports.build_reports) [MainThread] Dump junit report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/junit.xml 2024-11-19 13:51:18,048 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 13:51:18,048 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 13:51:18,048 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 13:51:18,048 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 13:51:18,048 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 13:51:18,048 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 13:51:18,048 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 13:51:18,049 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-19 13:51:18,049 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-19 13:51:18,049 INFO (build.reports.build_reports) [MainThread] Dump results report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/report.json 2024-11-19 13:51:18,049 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732024278.0495884 2024-11-19 13:51:18,049 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732024278.0495884 2024-11-19 13:51:18,049 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732024278.0495884 2024-11-19 13:51:18,050 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-19 13:51:18,050 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732024278.0506983 2024-11-19 13:51:18,050 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732024278.0506983 2024-11-19 13:51:18,050 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732024278.0506983 2024-11-19 13:51:18,050 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732024278.0508885 2024-11-19 13:51:18,050 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732024278.0508885 2024-11-19 13:51:18,050 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732024278.0508885 2024-11-19 13:51:18,051 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732024278.0514483 2024-11-19 13:51:18,051 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732024278.0514483 2024-11-19 13:51:18,051 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732024278.0514483 2024-11-19 13:51:18,051 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732024278.0516098 2024-11-19 13:51:18,051 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732024278.0516098 2024-11-19 13:51:18,051 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732024278.0516098 2024-11-19 13:51:18,051 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732024278.0517862 2024-11-19 13:51:18,051 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732024278.0517862 2024-11-19 13:51:18,051 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732024278.0517862 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000077 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000025 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000003 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000015 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000001 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000008 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000002 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000003 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000009 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000013 2024-11-19 13:51:18,052 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000007 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000001 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000001 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir']) 2024-11-19 13:51:18,053 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732024205.2928.352201uu 2024-11-19 13:51:18,053 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000570 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000005 2024-11-19 13:51:18,053 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000005 2024-11-19 13:51:18,054 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000006 2024-11-19 13:51:18,054 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.000928 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000006 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000004 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000006 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000007 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000003 2024-11-19 13:51:18,055 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])