2024-11-20 07:47:38,529 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-20 07:47:38,529 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000745 2024-11-20 07:47:38,530 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 07:47:38,530 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 07:47:38,530 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 07:47:38,530 DEBUG (root) [MainThread] vcs type: git 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000197 2024-11-20 07:47:38,530 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000410 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000001 2024-11-20 07:47:38,530 DEBUG (root) [MainThread] Start up timestamp 2024-11-20T07:47:38.UTC (2024-11-20T07:47:38.UTC) 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000060 2024-11-20 07:47:38,530 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000007 2024-11-20 07:47:38,531 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000403 2024-11-20 07:47:38,531 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732088858.2950.21o0dlr4 instead of None 2024-11-20 07:47:38,531 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000120 2024-11-20 07:47:38,531 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732088858.5315835 2024-11-20 07:47:38,531 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732088858.5315835 2024-11-20 07:47:38,531 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732088858.5315835 2024-11-20 07:47:38,532 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732088858.5320256 2024-11-20 07:47:38,532 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732088858.5320256 2024-11-20 07:47:38,532 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732088858.5320256 2024-11-20 07:47:38,532 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-20 07:47:38,544 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-20 07:47:38,544 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-20 07:47:38,544 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-20 07:47:38,544 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 07:47:38,545 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-20 07:47:38,545 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-20 07:47:38,545 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-20 07:47:38,546 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-20 07:47:38,546 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf` with `9` keys 2024-11-20 07:47:38,546 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 07:47:38,546 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-20 07:47:38,557 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-20 07:47:38,557 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-20 07:47:38,558 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732088858.5580838 2024-11-20 07:47:38,558 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732088858.5580838 2024-11-20 07:47:38,558 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732088858.5580838 2024-11-20 07:47:38,558 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732088858.5582078 2024-11-20 07:47:38,558 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732088858.5582078 2024-11-20 07:47:38,558 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732088858.5582078 2024-11-20 07:47:38,558 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-20 07:47:38,558 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 07:47:38,558 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-20 07:47:38,558 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-20 07:47:38,558 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000288 2024-11-20 07:47:38,560 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,560 DEBUG (root) [MainThread] Update replacements in 2024-11-20 07:47:38,560 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 07:47:38,560 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,560 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 07:47:38,561 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,561 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002274 2024-11-20 07:47:38,561 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000223 2024-11-20 07:47:38,561 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000041 2024-11-20 07:47:38,561 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,562 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000899 2024-11-20 07:47:38,562 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000031 2024-11-20 07:47:38,562 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 07:47:38,562 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 07:47:38,562 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 07:47:38,562 DEBUG (root) [MainThread] vcs type: git 2024-11-20 07:47:38,562 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000234 2024-11-20 07:47:38,562 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-20 07:47:38,563 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-20 07:47:38,563 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000109 2024-11-20 07:47:38,563 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-20 07:47:38,563 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000076 2024-11-20 07:47:38,563 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-20 07:47:38,563 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000002 2024-11-20 07:47:38,564 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 07:47:38,564 DEBUG (root) [MainThread] Update replacements in 2024-11-20 07:47:38,564 DEBUG (root) [MainThread] Update replacements in 2024-11-20 07:47:38,564 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001099 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000061 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000131 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-20 07:47:38,564 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000037 2024-11-20 07:47:38,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000004 2024-11-20 07:47:38,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000237 2024-11-20 07:47:38,565 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-20 07:47:38,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000375 2024-11-20 07:47:38,603 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.037984 2024-11-20 07:47:38,604 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-20 07:47:38,604 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732088858.6043322 2024-11-20 07:47:38,604 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732088858.6043322 2024-11-20 07:47:38,604 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732088858.6043322 2024-11-20 07:47:38,604 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732088858.6044872 2024-11-20 07:47:38,604 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732088858.6044872 2024-11-20 07:47:38,604 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732088858.6044872 2024-11-20 07:47:38,727 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732088858.7272093 2024-11-20 07:47:38,727 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732088858.7272093 2024-11-20 07:47:38,727 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732088858.7272093 2024-11-20 07:47:38,727 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732088858.7274153 2024-11-20 07:47:38,727 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732088858.7274153 2024-11-20 07:47:38,727 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732088858.7274153 2024-11-20 07:47:38,727 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732088858.727534 2024-11-20 07:47:38,727 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732088858.727534 2024-11-20 07:47:38,727 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732088858.727534 2024-11-20 07:47:38,727 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-20 07:47:38,727 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-20 07:47:38,727 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-20 07:47:38,727 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732088858.7277427 2024-11-20 07:47:38,727 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732088858.7277427 2024-11-20 07:47:38,727 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732088858.7277427 2024-11-20 07:47:38,727 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732088858.7279725 2024-11-20 07:47:38,728 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732088858.7279725 2024-11-20 07:47:38,728 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732088858.7279725 2024-11-20 07:47:38,730 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-20 07:47:38,730 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-20 07:47:38,730 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-20 07:47:38,732 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.UZ70f3eVUY' file to obtain bazel remote password 2024-11-20 07:47:38,732 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732088858.7322605 2024-11-20 07:47:38,732 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732088858.7322605 2024-11-20 07:47:38,732 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732088858.7322605 2024-11-20 07:47:38,732 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-20 07:47:38,733 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-20 07:47:38,733 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 07:47:38,733 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-20 07:47:38,733 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 07:47:38,734 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-20 07:47:38,736 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-20 07:47:38,738 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-20 07:47:38,738 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-20 07:47:38,738 DEBUG (build.graph) [MainThread] target toolchain: { "bottle_name": "clang18", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "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-20 07:47:38,738 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-20 07:47:38,739 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-20 07:47:38,739 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-20 07:47:38,739 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 07:47:38,739 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 7448917907 from sbr:7448917907 to /home/runner/.ya/tools/v4/7448917907 dir, post_process=0) 2024-11-20 07:47:38,739 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-20 07:47:38,740 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-20 07:47:38,748 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-20 07:47:38,748 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-20 07:47:38,748 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 07:47:38,749 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ya-tc/public.resources.json 2024-11-20 07:47:38,749 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-20 07:47:38,749 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-20 07:47:38,749 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 6812260917 from sbr:6812260917 to /home/runner/.ya/tools/v4/6812260917 dir, post_process=0) 2024-11-20 07:47:38,749 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 07:47:38,749 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-20 07:47:38,749 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-20 07:47:38,749 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 07:47:38,749 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 07:47:38,749 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-20 07:47:38,753 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2376, 12695, 'unix:/home/runner/.ya/tools/v4/psingXDlpse.tmp') 2024-11-20 07:47:38,758 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 07:47:38,759 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-20 07:47:38,759 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-20 07:47:38,759 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.02049875259399414 2024-11-20 07:47:38,759 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088858.7599306 2024-11-20 07:47:38,760 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_started=1732088858.7599306 2024-11-20 07:47:38,760 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_started - 1732088858.7599306 2024-11-20 07:47:38,760 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088858.7604134 2024-11-20 07:47:38,761 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started=1732088858.7604134 2024-11-20 07:47:38,761 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started - 1732088858.7604134 2024-11-20 07:47:38,761 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732088858.7607467 2024-11-20 07:47:38,761 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732088858.7607467 2024-11-20 07:47:38,761 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732088858.7607467 2024-11-20 07:47:38,761 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088858.7613623 2024-11-20 07:47:38,761 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started=1732088858.7613623 2024-11-20 07:47:38,762 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started - 1732088858.7613623 2024-11-20 07:47:38,762 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-20 07:47:38,762 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088858.7614024 2024-11-20 07:47:38,762 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started=1732088858.7614024 2024-11-20 07:47:38,762 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started - 1732088858.7614024 2024-11-20 07:47:38,762 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-20 07:47:38,762 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732088858.7616792 2024-11-20 07:47:38,763 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732088858.7616792 2024-11-20 07:47:38,763 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732088858.7616792 2024-11-20 07:47:38,763 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-20 07:47:38,766 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-20 07:47:38,768 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'97ac7f4cdd7ea53989f6536180a4683bd93a2c36', author:b's-sabitova ', summary:b'Merge ef140f9c04cfcf585168157a8633cfeb81f808d7 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0', svn_id:b'' 2024-11-20 07:47:38,769 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-snisk46tce Host information: Linux ghrun-snisk46tce 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-20 07:47:38,769 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"97ac7f4cdd7ea53989f6536180a4683bd93a2c36\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"s-sabitova \",\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-20T07:47:38.000000Z\",\n \"BUILD_HOST\": \"ghrun-snisk46tce\",\n \"BUILD_TIMESTAMP\": 1732088858,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: 97ac7f4cdd7ea53989f6536180a4683bd93a2c36\\n Branch: \\n Author: s-sabitova \\n Summary: Merge ef140f9c04cfcf585168157a8633cfeb81f808d7 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\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-snisk46tce\\n Host information: \\n Linux ghrun-snisk46tce 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: 97ac7f4cdd7ea53989f6536180a4683bd93a2c36\\n Branch: \\n Author: s-sabitova \\n Summary: Merge ef140f9c04cfcf585168157a8633cfeb81f808d7 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\n\",\n \"VCS\": \"git\"\n}" 2024-11-20 07:47:38,769 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.036248207092285156 2024-11-20 07:47:38,797 DEBUG (build.genconf) [Misc-03] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-3798d7c5263eb68d1e51bedf06eb099e/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-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-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', '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-20 07:47:38,799 DEBUG (build.genconf) [Misc-02] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-98d764e15a8af720dfdf42d2b66ea646/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-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-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', '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-20 07:47:38,927 DEBUG (build.genconf) [Misc-03] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-3798d7c5263eb68d1e51bedf06eb099e/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf 2024-11-20 07:47:38,928 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-20 07:47:38,928 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088858.9283051 2024-11-20 07:47:38,928 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished=1732088858.9283051 2024-11-20 07:47:38,928 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732088858.9283051 2024-11-20 07:47:38,928 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088858.9285226 2024-11-20 07:47:38,928 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started=1732088858.9285226 2024-11-20 07:47:38,928 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started - 1732088858.9285226 2024-11-20 07:47:38,928 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-relwithdebinfo-nopic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/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 0x7fdfaa06c360>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/makefiles.dart'} 2024-11-20 07:47:38,928 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-20 07:47:38,929 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-20 07:47:38,929 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 07:47:38,929 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-20 07:47:38,929 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/1732088858.2950.21o0dlr4/yatmp3h12g405 --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/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-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 07:47:38,929 DEBUG (build.genconf) [Misc-02] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-98d764e15a8af720dfdf42d2b66ea646/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf 2024-11-20 07:47:38,932 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-20 07:47:38,932 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088858.9328806 2024-11-20 07:47:38,932 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished=1732088858.9328806 2024-11-20 07:47:38,933 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished - 1732088858.9328806 2024-11-20 07:47:38,933 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088858.9331434 2024-11-20 07:47:38,933 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started=1732088858.9331434 2024-11-20 07:47:38,933 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started - 1732088858.9331434 2024-11-20 07:47:38,933 DEBUG (build.ymake2) [Misc-02] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-relwithdebinfo-pic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/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 0x7fdfa937b380>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/makefiles.dart'} 2024-11-20 07:47:38,933 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-20 07:47:38,933 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-20 07:47:38,933 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-20 07:47:38,934 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/1732088858.2950.21o0dlr4/yatmpyv5t6t1b --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/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-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732088858936773,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088858936896,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732088858936933,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088858936942,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732088858936946,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732088858937199,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:38,937 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732088858937204,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732088858939978,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088858940082,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732088858940111,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088858940120,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732088858940123,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732088858940371,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:38,940 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732088858940376,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,097 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732088859096473,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,097 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732088859096498,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,097 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732088859097848,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,098 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732088859097859,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,098 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732088859098562,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,099 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732088859098581,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,099 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732088859099065,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,099 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732088859099074,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,100 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732088859099977,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,100 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732088859099989,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,101 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732088859101164,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,101 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732088859101172,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,222 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732088859222188,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,224 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732088859223984,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,253 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253015,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,253 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253035,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,253 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253040,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,253 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253136,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,253 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732088859253140,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,254 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253196,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,254 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253201,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,254 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253205,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,254 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253209,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,254 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253212,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253216,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254766,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253219,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254785,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859253222,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254790,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732088859254102,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254895,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,255 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859254111,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732088859254900,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732088859254119,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254955,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732088859254123,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254960,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732088859254128,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254964,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859254133,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254968,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,256 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732088859254138,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254972,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732088859254142,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254982,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732088859254146,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254986,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859254990,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732088859255835,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859255843,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732088859255850,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,257 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732088859255854,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,258 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732088859255858,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,258 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859255863,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,258 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732088859255867,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-20 07:47:39,258 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732088859255871,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-20 07:47:39,258 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732088859255874,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,355 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09958733083,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732088859355477,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732088859355502,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.1014017875,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732088859355557,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859355511,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732088859355576,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859355518,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859355584,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,356 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732088859355570,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859355590,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,357 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":3055,"Sub":"","Type":"Debug","_timestamp":1732088859355650,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732088859355637,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732088859355653,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,357 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":3053,"Sub":"","Type":"Debug","_timestamp":1732088859355713,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732088859355717,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9021631; load time = 10879; loaded MD5 time = 786; Max loaded MD5 time = 5; loaded count = 2174; max load time = 39; mapped size = 40632376; mapped MD5 time = 7929; Max mapped MD5 time = 471; mapped count = 130; map time = 802; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3146; lstat min us = 1; lstat avr us = 1; lstat max us = 23; 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 = 2046; readdir min us = 0; readdir avr us = 0; readdir max us = 43; (opendir + readdir) sum us = 2046; (lstat + opendir + readdir) sum us = 5192;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732088859355688,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,357 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9021631; load time = 10871; loaded MD5 time = 803; Max loaded MD5 time = 5; loaded count = 2174; max load time = 28; mapped size = 40632376; mapped MD5 time = 7923; Max mapped MD5 time = 471; mapped count = 130; map time = 813; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3714; lstat min us = 1; lstat avr us = 1; lstat max us = 19; 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 = 2429; readdir min us = 0; readdir avr us = 0; readdir max us = 41; (opendir + readdir) sum us = 2429; (lstat + opendir + readdir) sum us = 6143;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732088859355749,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2046,"LoadTime":10879,"LoadedCount":2174,"LoadedMD5Time":786,"LoadedSize":9021631,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5192,"LstatMaxUs":23,"LstatMinUs":1,"LstatSumUs":3146,"MapTime":802,"MappedCount":130,"MappedMD5Time":7929,"MappedSize":40632376,"MaxLoadTime":39,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":471,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":43,"ReaddirMinUs":0,"ReaddirSumUs":2046,"_timestamp":1732088859355701,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2429,"LoadTime":10871,"LoadedCount":2174,"LoadedMD5Time":803,"LoadedSize":9021631,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":6143,"LstatMaxUs":19,"LstatMinUs":1,"LstatSumUs":3714,"MapTime":813,"MappedCount":130,"MappedMD5Time":7923,"MappedSize":40632376,"MaxLoadTime":28,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":471,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":41,"ReaddirMinUs":0,"ReaddirSumUs":2429,"_timestamp":1732088859355760,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9021631,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732088859355713,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9021631,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732088859355772,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":10879,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732088859355717,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":10871,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732088859355776,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732088859355720,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732088859355779,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732088859355723,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,358 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732088859355782,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":802,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732088859355725,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":813,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732088859355785,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732088859355728,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732088859355788,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732088859355731,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732088859355791,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9021631; load time = 10879; loaded count = 2174; max load time = 39;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732088859355738,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9021631; load time = 10871; loaded count = 2174; max load time = 28;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732088859355798,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":10879,"LoadedCount":2174,"LoadedSize":9021631,"MaxLoadTime":39,"_timestamp":1732088859355743,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-20 07:47:39,359 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":10871,"LoadedCount":2174,"LoadedSize":9021631,"MaxLoadTime":28,"_timestamp":1732088859355803,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 3; 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":1732088859355754,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 3; 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":1732088859355813,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732088859355759,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732088859355818,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-20 07:47:39,360 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":1732088859355773,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,360 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":1732088859355829,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732088859355778,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-20 07:47:39,360 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732088859355834,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-20 07:47:39,360 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":1732088859355792,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,361 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":1732088859355848,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732088859355796,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732088859355852,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732088859355801,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732088859355857,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732088859355804,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732088859355859,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-20 07:47:39,361 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":1732088859355812,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,361 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":1732088859355866,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732088859355817,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-20 07:47:39,361 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732088859355870,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732088859355820,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732088859355874,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,362 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":1732088859355923,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,362 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":1732088859355964,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732088859355929,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732088859355968,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732088859355933,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732088859355971,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732088859355936,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,362 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732088859355974,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732088859355939,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732088859355977,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732088859355941,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732088859355980,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732088859355951,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732088859355988,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732088859356524,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732088859356539,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732088859356526,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,363 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732088859356541,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732088859359040,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732088859359060,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732088859359044,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732088859359063,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732088859359093,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732088859359111,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,364 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-relwithdebinfo-pic). Tool targets: set() 2024-11-20 07:47:39,364 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732088859359095,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,364 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-relwithdebinfo-nopic). Tool targets: set() 2024-11-20 07:47:39,365 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732088859359113,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,365 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732088859.3651452 2024-11-20 07:47:39,365 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732088859359116,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,365 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732088859.3651452 2024-11-20 07:47:39,365 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732088859.3651452 2024-11-20 07:47:39,365 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-20 07:47:39,365 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732088859.3657017 2024-11-20 07:47:39,365 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732088859.3657017 2024-11-20 07:47:39,365 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732088859.3657017 2024-11-20 07:47:39,365 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732088859.3659093 2024-11-20 07:47:39,365 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732088859.3659093 2024-11-20 07:47:39,366 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732088859.3659093 2024-11-20 07:47:39,366 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732088859.3661213 2024-11-20 07:47:39,366 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732088859.3661213 2024-11-20 07:47:39,366 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732088859.3661213 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732088859359118,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732088859359098,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732088859359120,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732088859359100,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732088859359122,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732088859359102,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,366 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732088859359177,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,367 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732088859359105,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,367 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732088859359179,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,367 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732088859359160,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,367 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":1732088859360079,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,367 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732088859359163,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,367 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":1732088859360090,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-20 07:47:39,367 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":1732088859360110,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,367 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859360097,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,367 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":1732088859360120,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859360103,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859360127,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732088859361614,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859360133,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732088859361620,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732088859361390,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732088859362030,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732088859361396,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732088859362060,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732088859361809,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-20 07:47:39,368 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732088859362063,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732088859361841,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732088859362493,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732088859361843,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859362614,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732088859362278,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732088859362619,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859362401,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732088859362622,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732088859362406,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,369 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859362625,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732088859362408,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732088859362658,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859362411,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732088859362660,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732088859362443,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732088859362664,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732088859362446,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732088859362801,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732088859362450,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732088859362833,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,370 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732088859362595,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732088859363500,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732088859362617,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732088859363554,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732088859363311,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732088859363558,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732088859363371,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859363563,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732088859363377,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732088859363566,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,371 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859363382,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732088859366533,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732088859363385,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732088859366538,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732088859366341,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732088859366345,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859366549,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,372 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859366358,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,374 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":1732088859373883,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,374 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":1732088859373911,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,374 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":1732088859373891,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-20 07:47:39,374 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":1732088859373919,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-20 07:47:39,374 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859373899,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,374 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859373927,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,374 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.007366986023,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732088859373909,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,374 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.007587163983,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732088859373937,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732088859373915,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732088859373941,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732088859373918,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732088859373944,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859374922,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859374968,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732088859374928,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732088859374983,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732088859374945,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-20 07:47:39,375 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732088859375002,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-20 07:47:39,376 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":1732088859374959,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,376 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":1732088859375016,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,376 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":1732088859374965,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-20 07:47:39,376 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":1732088859375022,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01148463198,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732088859375046,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01171752561,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732088859375098,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732088859375050,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732088859375102,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732088859375073,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732088859375124,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,376 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732088859375076,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,377 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732088859375127,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,377 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":1732088859375084,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,377 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":1732088859375135,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,377 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732088859375089,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-20 07:47:39,377 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732088859375139,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859377990,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859377991,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859378006,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859378007,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732088859378011,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732088859378011,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859378024,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859378025,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,378 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3053,"Sub":"","Type":"Debug","_timestamp":1732088859378036,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3055,"Sub":"","Type":"Debug","_timestamp":1732088859378037,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732088859378650,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732088859378650,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4418646301,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732088859378657,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.438659662,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732088859378657,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732088859378661,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 07:47:39,379 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732088859378661,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 07:47:39,384 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/1732088858.2950.21o0dlr4/yatmp3h12g405 --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/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-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 07:47:39,384 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/1732088858.2950.21o0dlr4/yatmpyv5t6t1b --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/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-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 07:47:39,385 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732088858.929006576538086,"finish":1732088858.929644584655762,"duration":0.000638008117676},"execution":{"start":1732088858.929645538330078,"finish":1732088859.384354591369629,"duration":0.454709053039551},"postprocessing":{"start":1732088859.384356498718262,"finish":1732088859.384635210037231,"duration":0.00027871131897}},"purpose":"default-linux-x86_64-relwithdebinfo-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":1732088858.936773061752319,"finish":1732088859.378660917282104,"duration":0.441887855529785},"Load configuration from cache":{"start":1732088858.936933040618896,"finish":1732088858.936945915222168,"duration":0.000012874603271},"Load configuration (no cache)":{"start":1732088858.937203884124756,"finish":1732088859.096472978591919,"duration":0.159269094467163},"Compile and RecalcAll conditions":{"start":1732088859.096498012542725,"finish":1732088859.097847938537598,"duration":0.001349925994873},"Fold global commands":{"start":1732088859.097858905792236,"finish":1732088859.099065065383911,"duration":0.001206159591675},"Load plugins":{"start":1732088859.099073886871338,"finish":1732088859.222187995910645,"duration":0.123114109039307},"ymake main":{"start":1732088859.253139972686768,"finish":1732088859.378649950027466,"duration":0.125509977340698},"Build graph":{"start":1732088859.254127979278564,"finish":1732088859.355874061584473,"duration":0.101746082305908},"Configure graph":{"start":1732088859.254146099090576,"finish":1732088859.355576038360596,"duration":0.10142993927002},"Find Lost Includes":{"start":1732088859.355637073516846,"finish":1732088859.355716943740845,"duration":0.000079870223999},"Set reachable nodes":{"start":1732088859.355988025665283,"finish":1732088859.356539011001587,"duration":0.000550985336304},"Sort edges":{"start":1732088859.35654091835022,"finish":1732088859.359060049057007,"duration":0.002519130706787},"Report Foreign Platform Events":{"start":1732088859.359062910079956,"finish":1732088859.359111070632935,"duration":0.000048160552979},"Check blacklist":{"start":1732088859.359112977981567,"finish":1732088859.359116077423096,"duration":0.000003099441528},"Check isolated projects":{"start":1732088859.359117984771728,"finish":1732088859.359119892120361,"duration":0.000001907348633},"Report Configure Events":{"start":1732088859.359122037887573,"finish":1732088859.359177112579346,"duration":0.000055074691772},"Save and compact":{"start":1732088859.359179019927978,"finish":1732088859.361613988876343,"duration":0.002434968948364},"Detect loops":{"start":1732088859.36161994934082,"finish":1732088859.362060070037842,"duration":0.000440120697021},"Apply Dependency Management":{"start":1732088859.362062931060791,"finish":1732088859.362622022628784,"duration":0.000559091567993},"Save Dependency management cache":{"start":1732088859.36249303817749,"finish":1732088859.362618923187256,"duration":0.000125885009766},"Fill package outputs":{"start":1732088859.362658023834228,"finish":1732088859.362659931182861,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732088859.362663984298706,"finish":1732088859.362801074981689,"duration":0.000137090682983},"Find missing peerdirs":{"start":1732088859.362833023071289,"finish":1732088859.363500118255615,"duration":0.000667095184326},"Export JSON":{"start":1732088859.363554000854492,"finish":1732088859.375072956085205,"duration":0.011518955230713},"Write JSON":{"start":1732088859.363558053970337,"finish":1732088859.375050067901611,"duration":0.011492013931274},"Visit JSON":{"start":1732088859.363565921783447,"finish":1732088859.366533041000366,"duration":0.002967119216919},"Render JSON":{"start":1732088859.366538047790527,"finish":1732088859.373914957046509,"duration":0.007376909255981},"Save Uids cache":{"start":1732088859.373918056488037,"finish":1732088859.374927997589111,"duration":0.001009941101074}},"metrics":{"File access":{"loaded size":9021631,"load time":10871,"loaded MD5 time":803,"Max loaded MD5 time":5,"loaded count":2174,"max load time":28,"mapped size":40632376,"mapped MD5 time":7923,"Max mapped MD5 time":471,"mapped count":130,"map time":813,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3714,"lstat min us":1,"lstat avr us":1,"lstat max us":19,"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":2429,"readdir min us":0,"readdir avr us":0,"readdir max us":41,"(opendir + readdir) sum us":2429,"(lstat + opendir + readdir) sum us":6143},"Parsing":{"parse time":3,"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/1732088858.2950.21o0dlr4/yatmp3h12g405","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/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-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph._1oxvaia/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.454709053039551} 2024-11-20 07:47:39,385 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732088859.3855398 2024-11-20 07:47:39,385 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732088859.3855398 2024-11-20 07:47:39,385 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732088859.3855398 2024-11-20 07:47:39,385 DEBUG (core.event_handling.base_subscriber) [Misc-02] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 07:47:39,386 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732088858.933580875396728,"finish":1732088858.934111833572388,"duration":0.000530958175659},"execution":{"start":1732088858.934112787246704,"finish":1732088859.385115385055542,"duration":0.451002597808838},"postprocessing":{"start":1732088859.385117530822754,"finish":1732088859.385981321334839,"duration":0.000863790512085}},"purpose":"default-linux-x86_64-relwithdebinfo-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":1732088858.939977884292602,"finish":1732088859.378660917282104,"duration":0.438683032989502},"Load configuration from cache":{"start":1732088858.940110921859741,"finish":1732088858.940123081207275,"duration":0.000012159347534},"Load configuration (no cache)":{"start":1732088858.940376043319702,"finish":1732088859.098562002182007,"duration":0.158185958862305},"Compile and RecalcAll conditions":{"start":1732088859.098581075668335,"finish":1732088859.099977016448975,"duration":0.00139594078064},"Fold global commands":{"start":1732088859.09998893737793,"finish":1732088859.101164102554321,"duration":0.001175165176392},"Load plugins":{"start":1732088859.101171970367432,"finish":1732088859.223984003067017,"duration":0.122812032699585},"ymake main":{"start":1732088859.254899978637695,"finish":1732088859.378649950027466,"duration":0.12374997138977},"Build graph":{"start":1732088859.255857944488525,"finish":1732088859.355819940567017,"duration":0.099961996078491},"Configure graph":{"start":1732088859.255873918533325,"finish":1732088859.355501890182495,"duration":0.09962797164917},"Find Lost Includes":{"start":1732088859.355570077896118,"finish":1732088859.355653047561646,"duration":0.000082969665527},"Set reachable nodes":{"start":1732088859.355951070785522,"finish":1732088859.356523990631104,"duration":0.000572919845581},"Sort edges":{"start":1732088859.356525897979736,"finish":1732088859.359040021896362,"duration":0.002514123916626},"Report Foreign Platform Events":{"start":1732088859.359044075012207,"finish":1732088859.359092950820923,"duration":0.000048875808716},"Check blacklist":{"start":1732088859.359095096588135,"finish":1732088859.359097957611084,"duration":0.000002861022949},"Check isolated projects":{"start":1732088859.359100103378296,"finish":1732088859.359102010726929,"duration":0.000001907348633},"Report Configure Events":{"start":1732088859.359105110168457,"finish":1732088859.35915994644165,"duration":0.000054836273193},"Save and compact":{"start":1732088859.359163045883179,"finish":1732088859.361390113830566,"duration":0.002227067947388},"Detect loops":{"start":1732088859.361396074295044,"finish":1732088859.361840963363647,"duration":0.000444889068604},"Apply Dependency Management":{"start":1732088859.361843109130859,"finish":1732088859.362407922744751,"duration":0.000564813613892},"Save Dependency management cache":{"start":1732088859.362277984619141,"finish":1732088859.362406015396118,"duration":0.000128030776978},"Fill package outputs":{"start":1732088859.362442970275879,"finish":1732088859.362446069717407,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732088859.362449884414673,"finish":1732088859.362595081329346,"duration":0.000145196914673},"Find missing peerdirs":{"start":1732088859.362617015838623,"finish":1732088859.363311052322388,"duration":0.000694036483765},"Export JSON":{"start":1732088859.363370895385742,"finish":1732088859.375123977661133,"duration":0.011753082275391},"Write JSON":{"start":1732088859.363377094268799,"finish":1732088859.375102043151856,"duration":0.011724948883057},"Visit JSON":{"start":1732088859.363384962081909,"finish":1732088859.366341114044189,"duration":0.00295615196228},"Render JSON":{"start":1732088859.366344928741455,"finish":1732088859.373940944671631,"duration":0.007596015930176},"Save Uids cache":{"start":1732088859.373944044113159,"finish":1732088859.374983072280884,"duration":0.001039028167725}},"metrics":{"File access":{"loaded size":9021631,"load time":10879,"loaded MD5 time":786,"Max loaded MD5 time":5,"loaded count":2174,"max load time":39,"mapped size":40632376,"mapped MD5 time":7929,"Max mapped MD5 time":471,"mapped count":130,"map time":802,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3146,"lstat min us":1,"lstat avr us":1,"lstat max us":23,"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":2046,"readdir min us":0,"readdir avr us":0,"readdir max us":43,"(opendir + readdir) sum us":2046,"(lstat + opendir + readdir) sum us":5192},"Parsing":{"parse time":3,"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/1732088858.2950.21o0dlr4/yatmpyv5t6t1b","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/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-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.hdstmh6i/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.451002597808838} 2024-11-20 07:47:39,386 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732088859.3866022 2024-11-20 07:47:39,386 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732088859.3866022 2024-11-20 07:47:39,386 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732088859.3866022 2024-11-20 07:47:39,386 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732088859.3868968 2024-11-20 07:47:39,387 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732088859.3868968 2024-11-20 07:47:39,387 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732088859.3868968 2024-11-20 07:47:39,387 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088859.387177 2024-11-20 07:47:39,387 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished=1732088859.387177 2024-11-20 07:47:39,387 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732088859.387177 2024-11-20 07:47:39,387 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088859.3875062 2024-11-20 07:47:39,387 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started=1732088859.3875062 2024-11-20 07:47:39,387 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started - 1732088859.3875062 2024-11-20 07:47:39,387 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732088859.3876994 2024-11-20 07:47:39,387 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732088859.3876994 2024-11-20 07:47:39,387 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732088859.3876994 2024-11-20 07:47:39,388 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088859.3880286 2024-11-20 07:47:39,388 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished=1732088859.3880286 2024-11-20 07:47:39,388 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished - 1732088859.3880286 2024-11-20 07:47:39,388 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088859.3883133 2024-11-20 07:47:39,388 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_started=1732088859.3883133 2024-11-20 07:47:39,388 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_started - 1732088859.3883133 2024-11-20 07:47:39,393 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-20 07:47:39,393 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-20 07:47:39,393 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.0004429817199707031 2024-11-20 07:47:39,393 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.0002372264862060547 2024-11-20 07:47:39,393 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088859.3936658 2024-11-20 07:47:39,393 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished=1732088859.3936658 2024-11-20 07:47:39,393 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732088859.3936658 2024-11-20 07:47:39,393 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088859.3937666 2024-11-20 07:47:39,394 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished=1732088859.3937666 2024-11-20 07:47:39,394 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished - 1732088859.3937666 2024-11-20 07:47:39,394 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732088859.394254 2024-11-20 07:47:39,394 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished=1732088859.394254 2024-11-20 07:47:39,394 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732088859.394254 2024-11-20 07:47:39,394 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732088859.3946574 2024-11-20 07:47:39,394 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished=1732088859.3946574 2024-11-20 07:47:39,394 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished - 1732088859.3946574 2024-11-20 07:47:39,395 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-relwithdebinfo: 0.02909398078918457 2024-11-20 07:47:39,395 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732088859.3953261 2024-11-20 07:47:39,395 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732088859.3953261 2024-11-20 07:47:39,395 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732088859.3953261 2024-11-20 07:47:39,395 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732088859.3955913 2024-11-20 07:47:39,395 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732088859.3955913 2024-11-20 07:47:39,395 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732088859.3955913 2024-11-20 07:47:39,395 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-relwithdebinfo: 0.0005261898040771484 2024-11-20 07:47:39,395 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732088859.3957813 2024-11-20 07:47:39,395 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732088859.3957813 2024-11-20 07:47:39,395 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732088859.3957813 2024-11-20 07:47:39,395 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732088859.3959234 2024-11-20 07:47:39,395 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732088859.3959234 2024-11-20 07:47:39,396 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732088859.3959234 2024-11-20 07:47:39,396 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-relwithdebinfo: 0.00035572052001953125 2024-11-20 07:47:39,396 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-20 07:47:39,396 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732088859.3961837 2024-11-20 07:47:39,396 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732088859.3961837 2024-11-20 07:47:39,396 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732088859.3961837 2024-11-20 07:47:39,396 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732088859.3963397 2024-11-20 07:47:39,396 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732088859.3963397 2024-11-20 07:47:39,396 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732088859.3963397 2024-11-20 07:47:39,396 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-relwithdebinfo: 0.0003845691680908203 2024-11-20 07:47:39,396 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-20 07:47:39,396 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732088859.3965578 2024-11-20 07:47:39,396 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732088859.3965578 2024-11-20 07:47:39,396 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732088859.3965578 2024-11-20 07:47:39,396 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-20 07:47:39,397 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732088859.397173 2024-11-20 07:47:39,397 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732088859.397173 2024-11-20 07:47:39,397 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732088859.397173 2024-11-20 07:47:39,397 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732088859.3976247 2024-11-20 07:47:39,397 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732088859.3976247 2024-11-20 07:47:39,397 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732088859.3976247 2024-11-20 07:47:39,397 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-20 07:47:39,413 DEBUG (build.genconf) [Misc-04] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-c0348968119373774c0780f660d06e8f/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-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-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', '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-20 07:47:39,536 DEBUG (build.genconf) [Misc-04] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-c0348968119373774c0780f660d06e8f/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf 2024-11-20 07:47:39,537 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-20 07:47:39,537 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732088859.537145 2024-11-20 07:47:39,537 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732088859.537145 2024-11-20 07:47:39,537 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732088859.537145 2024-11-20 07:47:39,537 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732088859.5373669 2024-11-20 07:47:39,537 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732088859.5373669 2024-11-20 07:47:39,537 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732088859.5373669 2024-11-20 07:47:39,537 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-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', '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-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/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 0x7fdfa9337880>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/makefiles.dart'} 2024-11-20 07:47:39,537 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-20 07:47:39,537 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-20 07:47:39,537 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 07:47:39,538 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-20 07:47:39,538 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/1732088858.2950.21o0dlr4/yatmpz_lp37d2 --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/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-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/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-20 07:47:39,910 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/1732088858.2950.21o0dlr4/yatmpz_lp37d2 --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/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-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/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-20 07:47:39,910 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 07:47:39,911 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732088859.537792444229126,"finish":1732088859.538445472717285,"duration":0.000653028488159},"execution":{"start":1732088859.538446664810181,"finish":1732088859.910542249679565,"duration":0.372095584869385},"postprocessing":{"start":1732088859.910545587539673,"finish":1732088859.910944700241089,"duration":0.000399112701416}},"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/1732088858.2950.21o0dlr4/yatmpz_lp37d2","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/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-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/gen_graph.b7db4wnd/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.372095584869385} 2024-11-20 07:47:39,911 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732088859.9114192 2024-11-20 07:47:39,911 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732088859.9114192 2024-11-20 07:47:39,911 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732088859.9114192 2024-11-20 07:47:39,911 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732088859.9117014 2024-11-20 07:47:39,911 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732088859.9117014 2024-11-20 07:47:39,911 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732088859.9117014 2024-11-20 07:47:39,911 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732088859.9119098 2024-11-20 07:47:39,911 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732088859.9119098 2024-11-20 07:47:39,912 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732088859.9119098 2024-11-20 07:47:39,912 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732088859.9122043 2024-11-20 07:47:39,912 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732088859.9122043 2024-11-20 07:47:39,912 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732088859.9122043 2024-11-20 07:47:39,912 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732088859.9128232 2024-11-20 07:47:39,913 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732088859.9128232 2024-11-20 07:47:39,913 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732088859.9128232 2024-11-20 07:47:39,915 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732088859.914987 2024-11-20 07:47:39,915 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732088859.914987 2024-11-20 07:47:39,915 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732088859.914987 2024-11-20 07:47:39,915 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732088859.915212 2024-11-20 07:47:39,915 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732088859.915212 2024-11-20 07:47:39,915 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732088859.915212 2024-11-20 07:47:39,915 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732088859.9153776 2024-11-20 07:47:39,915 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732088859.9153776 2024-11-20 07:47:39,915 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732088859.9153776 2024-11-20 07:47:39,916 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732088859.91613 2024-11-20 07:47:39,916 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732088859.91613 2024-11-20 07:47:39,916 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732088859.91613 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7fdfa93f1ee0>' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-20 07:47:39,916 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-20 07:47:39,917 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-20 07:47:39,917 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-20 07:47:39,917 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-20 07:47:39,917 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-20 07:47:39,917 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-20 07:47:39,917 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-relwithdebinfo: 0.0010743141174316406 2024-11-20 07:47:39,917 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-relwithdebinfo 2024-11-20 07:47:39,917 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732088859.9174795 2024-11-20 07:47:39,917 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732088859.9174795 2024-11-20 07:47:39,917 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732088859.9174795 2024-11-20 07:47:39,917 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732088859.9176288 2024-11-20 07:47:39,917 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732088859.9176288 2024-11-20 07:47:39,917 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732088859.9176288 2024-11-20 07:47:39,917 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732088859.917757 2024-11-20 07:47:39,917 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732088859.917757 2024-11-20 07:47:39,917 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732088859.917757 2024-11-20 07:47:39,918 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-20 07:47:39,918 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 07:47:39,918 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-20 07:47:39,918 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-20 07:47:39,918 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732088859.9187124 2024-11-20 07:47:39,918 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732088859.9187124 2024-11-20 07:47:39,918 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732088859.9187124 2024-11-20 07:47:39,918 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732088859.9188921 2024-11-20 07:47:39,918 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732088859.9188921 2024-11-20 07:47:39,918 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732088859.9188921 2024-11-20 07:47:39,919 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732088859.9190533 2024-11-20 07:47:39,919 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732088859.9190533 2024-11-20 07:47:39,919 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732088859.9190533 2024-11-20 07:47:39,919 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.1599562168121338 2024-11-20 07:47:39,919 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732088859.9192433 2024-11-20 07:47:39,919 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732088859.9192433 2024-11-20 07:47:39,919 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732088859.9192433 2024-11-20 07:47:39,919 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 07:47:39,919 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00024819374084472656 2024-11-20 07:47:39,919 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732088859.91948 2024-11-20 07:47:39,919 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732088859.91948 2024-11-20 07:47:39,919 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732088859.91948 2024-11-20 07:47:39,919 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.00020170211791992188 2024-11-20 07:47:39,919 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 07:47:39,919 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 07:47:39,919 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732088859.9199443 2024-11-20 07:47:39,919 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732088859.9199443 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732088859.9199443 2024-11-20 07:47:39,920 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-20 07:47:39,920 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.344650268554688e-06 2024-11-20 07:47:39,920 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732088859.9201713 2024-11-20 07:47:39,920 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732088859.9201713 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732088859.9201713 2024-11-20 07:47:39,920 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006663799285888672 2024-11-20 07:47:39,920 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732088859.9203513 2024-11-20 07:47:39,920 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732088859.9203513 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732088859.9203513 2024-11-20 07:47:39,920 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732088859.920465 2024-11-20 07:47:39,920 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732088859.920465 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732088859.920465 2024-11-20 07:47:39,920 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732088859.9205945 2024-11-20 07:47:39,920 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732088859.9205945 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732088859.9205945 2024-11-20 07:47:39,920 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 07:47:39,920 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 07:47:39,920 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 07:47:39,920 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732088859.9208796 2024-11-20 07:47:39,920 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732088859.9208796 2024-11-20 07:47:39,920 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732088859.9208796 2024-11-20 07:47:39,921 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732088859.9210143 2024-11-20 07:47:39,921 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732088859.9210143 2024-11-20 07:47:39,921 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732088859.9210143 2024-11-20 07:47:39,921 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732088859.9213052 2024-11-20 07:47:39,921 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732088859.9213052 2024-11-20 07:47:39,921 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732088859.9213052 2024-11-20 07:47:39,921 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732088859.9214451 2024-11-20 07:47:39,921 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732088859.9214451 2024-11-20 07:47:39,921 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732088859.9214451 2024-11-20 07:47:39,921 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0012798309326171875 2024-11-20 07:47:39,921 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732088859.9216285 2024-11-20 07:47:39,921 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732088859.9216285 2024-11-20 07:47:39,921 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732088859.9216285 2024-11-20 07:47:39,921 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732088859.9218445 2024-11-20 07:47:39,921 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732088859.9218445 2024-11-20 07:47:39,921 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732088859.9218445 2024-11-20 07:47:39,922 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732088859.9221394 2024-11-20 07:47:39,922 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732088859.9221394 2024-11-20 07:47:39,922 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732088859.9221394 2024-11-20 07:47:39,922 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732088859.9222841 2024-11-20 07:47:39,922 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732088859.9222841 2024-11-20 07:47:39,922 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732088859.9222841 2024-11-20 07:47:39,922 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732088859.9224672 2024-11-20 07:47:39,922 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732088859.9224672 2024-11-20 07:47:39,922 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732088859.9224672 2024-11-20 07:47:39,922 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732088859.9226036 2024-11-20 07:47:39,922 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732088859.9226036 2024-11-20 07:47:39,922 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732088859.9226036 2024-11-20 07:47:39,922 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.1949691772460938 2024-11-20 07:47:39,924 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732088859.9243665 2024-11-20 07:47:39,924 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732088859.9243665 2024-11-20 07:47:39,924 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732088859.9243665 2024-11-20 07:47:39,924 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-20 07:47:39,924 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-20 07:47:39,924 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-20 07:47:39,924 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732088859.9246793 2024-11-20 07:47:39,924 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732088859.9246793 2024-11-20 07:47:39,924 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732088859.9246793 2024-11-20 07:47:39,924 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732088859.9248242 2024-11-20 07:47:39,924 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732088859.9248242 2024-11-20 07:47:39,924 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732088859.9248242 2024-11-20 07:47:39,924 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-20 07:47:39,925 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732088859.9250438 2024-11-20 07:47:39,925 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732088859.9250438 2024-11-20 07:47:39,925 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732088859.9250438 2024-11-20 07:47:39,925 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-20 07:47:39,927 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-20 07:47:39,928 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-20 07:47:39,929 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-20 07:47:39,930 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-20 07:47:39,931 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-20 07:47:39,932 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-20 07:47:39,933 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/6a7363665434576d706a453647487136455f6366565100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,934 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-20 07:47:39,934 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/3153706348734e534a7234665f486c7971573438325100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,935 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/41426c635462514e7a735f6970656170666939634f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,936 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-20 07:47:39,936 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing jscfT4WmpjE6GHq6E_cfVQ => False 2024-11-20 07:47:39,936 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/4a6a7670314c486156306b666272334f3641395f4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,938 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/676164567759646c31433869513353626f674c526c6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,938 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:39,939 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-20 07:47:39,939 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/7035496c73476c304e38335633747a516a784266797700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,940 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 1SpcHsNSJr4f_HlyqW482Q => False 2024-11-20 07:47:39,941 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/634a37735a764e46526e6c6551646144476d4952396700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,941 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing ABlcTbQNzs_ipeapfi9cOA => False 2024-11-20 07:47:39,941 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/6e616a6773584f6b51387832546b38416b4750345a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,944 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-20 07:47:39,945 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:39,945 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing Jjvp1LHaV0kfbr3O6A9_MQ => False 2024-11-20 07:47:39,946 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing gadVwYdl1C8iQ3SbogLRlg => False 2024-11-20 07:47:39,946 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 07:47:39,946 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing p5IlsGl0N83V3tzQjxBfyw => False 2024-11-20 07:47:39,947 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/6c35486f62486d5252307168765552376b485f44687700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,947 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing cJ7sZvNFRnleQdaDGmIR9g => False 2024-11-20 07:47:39,948 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing najgsXOkQ8x2Tk8AkGP4Zw => False 2024-11-20 07:47:39,948 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:39,948 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 07:47:39,949 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing l5HobHmRR0qhvUR7kH_Dhw => False 2024-11-20 07:47:39,950 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/75626f717466515367324a7a74507455304f4b68426700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:39,950 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 07:47:39,951 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing uboqtfQSg2JztPtU0OKhBg => False 2024-11-20 07:47:39,951 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-20 07:47:39,951 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 07:47:39,955 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732088859.955792 2024-11-20 07:47:39,955 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732088859.955792 2024-11-20 07:47:39,955 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732088859.955792 2024-11-20 07:47:39,956 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.03308224678039551 2024-11-20 07:47:39,956 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732088859.9562488 2024-11-20 07:47:39,956 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732088859.9562488 2024-11-20 07:47:39,956 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732088859.9562488 2024-11-20 07:47:39,956 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732088859.9564352 2024-11-20 07:47:39,956 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732088859.9564352 2024-11-20 07:47:39,956 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732088859.9564352 2024-11-20 07:47:39,956 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 07:47:39,956 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 07:47:39,957 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 07:47:39,957 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 07:47:39,957 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 07:47:39,957 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 07:47:39,957 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 07:47:39,958 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-20 07:47:39,958 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-20 07:47:39,958 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732088859.958624 2024-11-20 07:47:39,958 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732088859.958624 2024-11-20 07:47:39,958 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732088859.958624 2024-11-20 07:47:39,958 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-20 07:47:39,970 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732088858.2950.21o0dlr4/2950rhq60evj', '0'] 2024-11-20 07:47:39,971 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-20 07:47:39,996 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/rjvy/STAMP 2024-11-20 07:47:39,996 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 4.267692565917969e-05 2024-11-20 07:47:39,997 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.0001609325408935547 2024-11-20 07:47:39,997 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 6.842613220214844e-05 2024-11-20 07:47:39,997 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 6.365776062011719e-05 2024-11-20 07:47:39,997 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-20 07:47:40,104 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-20 07:47:40,109 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid Jjvp1LHaV0kfbr3O6A9_MQ) address: (2535, 17481, 'unix:/home/runner/.ya/build/cache/7/psingsHOanB.tmp') 2024-11-20 07:47:40,115 DEBUG (urllib3.connectionpool) [Worker-010] http://cachesrv.internal:8081 "HEAD /ac/634a37735a764e46526e6c6551646144476d4952396700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,115 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-010] Bazel-remote Probing cJ7sZvNFRnleQdaDGmIR9g => False 2024-11-20 07:47:40,117 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/676164567759646c31433869513353626f674c526c6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,117 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/3153706348734e534a7234665f486c7971573438325100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,117 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/4a6a7670314c486156306b666272334f3641395f4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,119 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-011] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-20 07:47:40,120 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing gadVwYdl1C8iQ3SbogLRlg => False 2024-11-20 07:47:40,120 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/6c35486f62486d5252307168765552376b485f44687700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,120 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-013] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-20 07:47:40,121 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 1SpcHsNSJr4f_HlyqW482Q created (provider=None) 2024-11-20 07:47:40,123 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing 1SpcHsNSJr4f_HlyqW482Q => False 2024-11-20 07:47:40,124 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "HEAD /ac/7035496c73476c304e38335633747a516a784266797700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,124 DEBUG (urllib3.connectionpool) [Worker-014] http://cachesrv.internal:8081 "HEAD /ac/41426c635462514e7a735f6970656170666939634f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,124 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing Jjvp1LHaV0kfbr3O6A9_MQ => False 2024-11-20 07:47:40,124 DEBUG (yalibrary.toolscache) [Worker-011] Tools cache attempt: (request for resource 7458630270) 2024-11-20 07:47:40,128 DEBUG (urllib3.connectionpool) [Worker-017] http://cachesrv.internal:8081 "HEAD /ac/75626f717466515367324a7a74507455304f4b68426700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,128 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "HEAD /ac/6e616a6773584f6b51387832546b38416b4750345a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,129 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing l5HobHmRR0qhvUR7kH_Dhw => False 2024-11-20 07:47:40,130 DEBUG (yalibrary.toolscache) [Worker-013] Tools cache attempt: (request for resource 7336031045) 2024-11-20 07:47:40,130 DEBUG (urllib3.connectionpool) [Worker-021] http://cachesrv.internal:8081 "HEAD /ac/6a7363665434576d706a453647487136455f6366565100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:40,130 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for ABlcTbQNzs_ipeapfi9cOA created (provider=None) 2024-11-20 07:47:40,131 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-016] Bazel-remote Probing p5IlsGl0N83V3tzQjxBfyw => False 2024-11-20 07:47:40,131 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-014] Bazel-remote Probing ABlcTbQNzs_ipeapfi9cOA => False 2024-11-20 07:47:40,132 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-017] Bazel-remote Probing uboqtfQSg2JztPtU0OKhBg => False 2024-11-20 07:47:40,133 DEBUG (yalibrary.toolscache) [Worker-011] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 07:47:40,133 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-020] Bazel-remote Probing najgsXOkQ8x2Tk8AkGP4Zw => False 2024-11-20 07:47:40,135 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-021] Bazel-remote Probing jscfT4WmpjE6GHq6E_cfVQ => False 2024-11-20 07:47:40,135 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:40,135 DEBUG (yalibrary.toolscache) [Worker-013] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 07:47:40,135 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for jscfT4WmpjE6GHq6E_cfVQ created (provider=None) 2024-11-20 07:47:40,138 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:40,139 DEBUG (yalibrary.fetcher.cache_helper) [Worker-011] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 07:47:40,143 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 07:47:40,144 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:40,144 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for Jjvp1LHaV0kfbr3O6A9_MQ created (provider=None) 2024-11-20 07:47:40,144 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-016] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 07:47:40,144 DEBUG (yalibrary.fetcher.cache_helper) [Worker-013] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 07:47:40,144 DEBUG (library.python.filelock) [Worker-011] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 07:47:40,145 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-020] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 07:47:40,146 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for gadVwYdl1C8iQ3SbogLRlg created (provider=None) 2024-11-20 07:47:40,146 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-20 07:47:40,147 DEBUG (yalibrary.fetcher.cache_helper) [Worker-011] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 07:47:40,147 DEBUG (library.python.filelock) [Worker-013] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 07:47:40,147 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for cJ7sZvNFRnleQdaDGmIR9g created (provider=None) 2024-11-20 07:47:40,149 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-20 07:47:40,149 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-20 07:47:40,149 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for l5HobHmRR0qhvUR7kH_Dhw created (provider=None) 2024-11-20 07:47:40,149 DEBUG (yalibrary.fetcher.cache_helper) [Worker-013] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 07:47:40,149 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-20 07:47:40,149 DEBUG (exts.http_client) [Worker-011] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.7rwqqxzk.kenovfnc.part, expect md5- 2024-11-20 07:47:40,151 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-20 07:47:40,152 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for p5IlsGl0N83V3tzQjxBfyw created (provider=None) 2024-11-20 07:47:40,153 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-20 07:47:40,153 DEBUG (exts.http_client) [Worker-013] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.ksgeg8ep.yrphw3zi.part, expect md5- 2024-11-20 07:47:40,154 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for najgsXOkQ8x2Tk8AkGP4Zw created (provider=None) 2024-11-20 07:47:40,162 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-20 07:47:40,163 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for uboqtfQSg2JztPtU0OKhBg created (provider=None) 2024-11-20 07:47:40,163 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-20 07:47:40,164 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-20 07:47:40,165 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-20 07:47:40,167 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-20 07:47:40,167 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-20 07:47:40,170 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-20 07:47:40,170 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-20 07:47:40,172 DEBUG (yalibrary.toolscache) [Worker-056] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-20 07:47:40,172 DEBUG (yalibrary.toolscache) [Worker-016] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-20 07:47:40,173 DEBUG (yalibrary.toolscache) [Worker-020] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-20 07:47:40,282 DEBUG (exts.http_client) [Worker-013] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Wed, 20 Nov 2024 07:47:40 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: 877ca6115ff9d2d9 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=KLG"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 07:47:40,303 DEBUG (exts.http_client) [Worker-011] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Wed, 20 Nov 2024 07:47:40 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: fb8ee210b00af0ec 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=KLG"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 07:47:40,455 DEBUG (exts.http_client) [Worker-013] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.ksgeg8ep, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.292357 2024-11-20 07:47:40,455 DEBUG (yalibrary.fetcher.common) [Worker-013] extract /home/runner/.ya/tools/v4/7336031045/resource.ksgeg8ep to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-20 07:47:40,613 DEBUG (library.python.filelock) [Worker-013] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 07:47:40,616 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (get uid 7e668419765a880924574f3e7c9c6053) 2024-11-20 07:47:40,620 DEBUG (yalibrary.toolscache) [Worker-070] AC cache attempt: (get uid 1263fb5b6c256f1c604c27af9f29c449) 2024-11-20 07:47:40,622 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/0d1da491cfb5f07d1b5ebe874b8c1a18917af5d60cb81a5dd4e223129e9d0a11 HTTP/1.1" 404 0 2024-11-20 07:47:40,622 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing 7e668419765a880924574f3e7c9c6053 => False 2024-11-20 07:47:40,623 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "HEAD /ac/dcc16c7ded0a019cca70f7dc949375cbbfdfa73d0b7f43f6d5bd41f544f71c11 HTTP/1.1" 404 0 2024-11-20 07:47:40,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-070] Bazel-remote Probing 1263fb5b6c256f1c604c27af9f29c449 => False 2024-11-20 07:47:40,623 DEBUG (root) [Worker-070] Run node jscfT4WmpjE6GHq6E_cfVQ in build root /home/runner/.ya/build/build_root/rjvy/000019 2024-11-20 07:47:40,624 DEBUG (root) [Worker-013] Run node gadVwYdl1C8iQ3SbogLRlg in build root /home/runner/.ya/build/build_root/rjvy/00000e 2024-11-20 07:47:41,028 DEBUG (yalibrary.toolscache) [Worker-072] AC cache attempt: (put uid gadVwYdl1C8iQ3SbogLRlg) 2024-11-20 07:47:41,029 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "HEAD /ac/676164567759646c31433869513353626f674c526c6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:41,029 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Bazel-remote Probing gadVwYdl1C8iQ3SbogLRlg => False 2024-11-20 07:47:41,030 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Put ydb/docs/ru/core/docs-ru-core.docslib(gadVwYdl1C8iQ3SbogLRlg) to Bazel-remote 2024-11-20 07:47:41,030 DEBUG (yalibrary.toolscache) [Worker-072] AC cache attempt: (put uid 7e668419765a880924574f3e7c9c6053) 2024-11-20 07:47:41,031 DEBUG (yalibrary.runner.tasks.result) [Worker-013] Result node gadVwYdl1C8iQ3SbogLRlg processed successfully: True 2024-11-20 07:47:41,034 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/733322f1668a96f4e8c59904fd6bd3bdac86b9a516e962682a4ed51e73aca96c HTTP/1.1" 200 0 2024-11-20 07:47:41,038 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/f88d39de38f108a96948de300c4f5ec36c689af2381ea4f9604d71a044673017 HTTP/1.1" 200 0 2024-11-20 07:47:41,287 DEBUG (yalibrary.runner.tasks.result) [Worker-085] Result node jscfT4WmpjE6GHq6E_cfVQ processed successfully: True 2024-11-20 07:47:41,288 DEBUG (yalibrary.toolscache) [Worker-089] AC cache attempt: (put uid jscfT4WmpjE6GHq6E_cfVQ) 2024-11-20 07:47:41,289 DEBUG (yalibrary.toolscache) [Worker-089] AC cache attempt: (put uid 1263fb5b6c256f1c604c27af9f29c449) 2024-11-20 07:47:41,297 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "HEAD /ac/6a7363665434576d706a453647487136455f6366565100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:41,297 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-070] Bazel-remote Probing jscfT4WmpjE6GHq6E_cfVQ => False 2024-11-20 07:47:41,298 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-070] Put ydb/docs/__docs_internal.docslib.fake(jscfT4WmpjE6GHq6E_cfVQ) to Bazel-remote 2024-11-20 07:47:41,318 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "PUT /cas/5c808914b25338bcead8d0b6d43acfd0ca630d7c419b1f6a6137f3cec25b7ea1 HTTP/1.1" 200 0 2024-11-20 07:47:41,321 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "PUT /cas/1674335161670d0891b8a97ae2b53654a29f60fe574370548a5bae84297d0a20 HTTP/1.1" 200 0 2024-11-20 07:47:41,362 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/7bf5779e3056dfcb892b9adfd0c5699c3fb76359a960067783517e3c14756930 HTTP/1.1" 200 0 2024-11-20 07:47:41,365 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /ac/676164567759646c31433869513353626f674c526c6700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:41,365 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Put ydb/docs/ru/core/docs-ru-core.docslib(gadVwYdl1C8iQ3SbogLRlg) size=29634833 to Bazel-remote completed 2024-11-20 07:47:41,848 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "PUT /cas/8e3b691dd4738b7dbc70a9a4a286d785711dbf6e38942156c54e5b86e2a0d026 HTTP/1.1" 200 0 2024-11-20 07:47:41,852 DEBUG (urllib3.connectionpool) [Worker-070] http://cachesrv.internal:8081 "PUT /ac/6a7363665434576d706a453647487136455f6366565100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:41,852 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-070] Put ydb/docs/__docs_internal.docslib.fake(jscfT4WmpjE6GHq6E_cfVQ) size=51589393 to Bazel-remote completed 2024-11-20 07:47:47,493 DEBUG (exts.http_client) [Worker-011] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.7rwqqxzk, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=7.340550 2024-11-20 07:47:47,493 DEBUG (yalibrary.fetcher.common) [Worker-011] extract /home/runner/.ya/tools/v4/7458630270/resource.7rwqqxzk to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-20 07:47:48,077 DEBUG (library.python.filelock) [Worker-011] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 07:47:48,079 DEBUG (yalibrary.toolscache) [Worker-011] AC cache attempt: (get uid 347425272ba519960ca58bf2f37c3b02) 2024-11-20 07:47:48,080 DEBUG (yalibrary.toolscache) [Worker-101] AC cache attempt: (get uid bad4b3e5eaf692c570a8f7b2e7112b8d) 2024-11-20 07:47:48,080 DEBUG (yalibrary.toolscache) [Worker-100] AC cache attempt: (get uid 4086e497f487949890f4ffeac9e4b7b3) 2024-11-20 07:47:48,080 DEBUG (yalibrary.toolscache) [Worker-099] AC cache attempt: (get uid 6b14167e5e67975e4ece4606f1e3d883) 2024-11-20 07:47:48,083 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "HEAD /ac/26f258cf04b871f8ad89c5a22b7e491002e9e3c1942a419c0ce91e8c0ae98b11 HTTP/1.1" 404 0 2024-11-20 07:47:48,084 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/bc83e88a35f2a5f2938400d1706c6eaef5efe4224aac3dc2a1ff7fc3df501d11 HTTP/1.1" 404 0 2024-11-20 07:47:48,084 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Bazel-remote Probing bad4b3e5eaf692c570a8f7b2e7112b8d => False 2024-11-20 07:47:48,084 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/6b3bc461c45324b8400003a8d393a763b3d37d0eb1c4a297ba76ad4db41bb711 HTTP/1.1" 404 0 2024-11-20 07:47:48,085 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing 4086e497f487949890f4ffeac9e4b7b3 => False 2024-11-20 07:47:48,086 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing 347425272ba519960ca58bf2f37c3b02 => False 2024-11-20 07:47:48,086 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/1e711116dbed963975fad089aee2998840c52c6cb297ae216aa775cf8e590211 HTTP/1.1" 404 0 2024-11-20 07:47:48,086 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing 6b14167e5e67975e4ece4606f1e3d883 => False 2024-11-20 07:47:48,089 DEBUG (root) [Worker-101] Run node p5IlsGl0N83V3tzQjxBfyw in build root /home/runner/.ya/build/build_root/rjvy/000013 2024-11-20 07:47:48,091 DEBUG (root) [Worker-100] Run node najgsXOkQ8x2Tk8AkGP4Zw in build root /home/runner/.ya/build/build_root/rjvy/000017 2024-11-20 07:47:48,122 DEBUG (root) [Worker-011] Run node Jjvp1LHaV0kfbr3O6A9_MQ in build root /home/runner/.ya/build/build_root/rjvy/000010 2024-11-20 07:47:48,141 DEBUG (root) [Worker-099] Run node ABlcTbQNzs_ipeapfi9cOA in build root /home/runner/.ya/build/build_root/rjvy/000014 2024-11-20 07:47:51,892 DEBUG (yalibrary.toolscache) [Worker-108] AC cache attempt: (put uid najgsXOkQ8x2Tk8AkGP4Zw) 2024-11-20 07:47:51,892 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/6e616a6773584f6b51387832546b38416b4750345a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:51,893 DEBUG (yalibrary.toolscache) [Worker-104] AC cache attempt: (get uid 49aa0635146afbb455c2f39201eed677) 2024-11-20 07:47:51,894 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing najgsXOkQ8x2Tk8AkGP4Zw => False 2024-11-20 07:47:51,894 DEBUG (yalibrary.toolscache) [Worker-108] AC cache attempt: (put uid 4086e497f487949890f4ffeac9e4b7b3) 2024-11-20 07:47:51,896 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(najgsXOkQ8x2Tk8AkGP4Zw) to Bazel-remote 2024-11-20 07:47:51,896 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "HEAD /ac/80504f9d3b3ffc73837b2edc97038bc2534cca06c53843f55d4bc43d2fad2b11 HTTP/1.1" 404 0 2024-11-20 07:47:51,896 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-104] Bazel-remote Probing 49aa0635146afbb455c2f39201eed677 => False 2024-11-20 07:47:51,899 DEBUG (yalibrary.runner.tasks.result) [Worker-106] Result node najgsXOkQ8x2Tk8AkGP4Zw processed successfully: True 2024-11-20 07:47:51,899 DEBUG (root) [Worker-104] Run node uboqtfQSg2JztPtU0OKhBg in build root /home/runner/.ya/build/build_root/rjvy/000015 2024-11-20 07:47:51,902 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/5786c90a7fe59401653f0f0d2c27fc58d251d8cb085877432b571e95b84e0762 HTTP/1.1" 200 0 2024-11-20 07:47:51,906 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/9dd6b536476cace3f486a0d4ec57bce4d854b2308ac296526e7cbf7639a350d8 HTTP/1.1" 200 0 2024-11-20 07:47:51,924 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/24b3a4b644653dddaae523d9f08f02689445bf546629f7ae2fe2cf4ad3d8d7c8 HTTP/1.1" 200 0 2024-11-20 07:47:51,927 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /ac/6e616a6773584f6b51387832546b38416b4750345a7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:51,927 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(najgsXOkQ8x2Tk8AkGP4Zw) size=1092256 to Bazel-remote completed 2024-11-20 07:47:52,003 DEBUG (yalibrary.toolscache) [Worker-120] AC cache attempt: (get uid 1b8303227b3bef9ad334e293138e6dde) 2024-11-20 07:47:52,003 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "HEAD /ac/7035496c73476c304e38335633747a516a784266797700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:52,003 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid p5IlsGl0N83V3tzQjxBfyw) 2024-11-20 07:47:52,005 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Bazel-remote Probing p5IlsGl0N83V3tzQjxBfyw => False 2024-11-20 07:47:52,007 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(p5IlsGl0N83V3tzQjxBfyw) to Bazel-remote 2024-11-20 07:47:52,007 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid bad4b3e5eaf692c570a8f7b2e7112b8d) 2024-11-20 07:47:52,007 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "HEAD /ac/cf27db8b9217931c9633720b2796eeedb6f22bc07e58b3b63fa8509e0c81f911 HTTP/1.1" 404 0 2024-11-20 07:47:52,008 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Bazel-remote Probing 1b8303227b3bef9ad334e293138e6dde => False 2024-11-20 07:47:52,008 DEBUG (yalibrary.runner.tasks.result) [Worker-118] Result node p5IlsGl0N83V3tzQjxBfyw processed successfully: True 2024-11-20 07:47:52,010 DEBUG (root) [Worker-120] Run node l5HobHmRR0qhvUR7kH_Dhw in build root /home/runner/.ya/build/build_root/rjvy/000012 2024-11-20 07:47:52,016 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/1296ca2df83f0fc93ffbe93c1f2d83c66be0f63780f9c572213899e1185e849b HTTP/1.1" 200 0 2024-11-20 07:47:52,019 DEBUG (yalibrary.toolscache) [Worker-135] AC cache attempt: (put uid Jjvp1LHaV0kfbr3O6A9_MQ) 2024-11-20 07:47:52,020 DEBUG (yalibrary.toolscache) [Worker-136] AC cache attempt: (get uid e1cbd7c596549eb95188426ded560fdb) 2024-11-20 07:47:52,021 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "HEAD /ac/4a6a7670314c486156306b666272334f3641395f4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:52,022 DEBUG (yalibrary.toolscache) [Worker-135] AC cache attempt: (put uid 347425272ba519960ca58bf2f37c3b02) 2024-11-20 07:47:52,022 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/e94bea27ce4c059799b34da49d5652673972a8771c5e7ab404fad2059e2aed58 HTTP/1.1" 200 0 2024-11-20 07:47:52,022 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "HEAD /ac/446a4f0e8ae9aed6afbcbe476358f0094180609e2eb78e3505fda43d3d194f11 HTTP/1.1" 404 0 2024-11-20 07:47:52,023 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Bazel-remote Probing Jjvp1LHaV0kfbr3O6A9_MQ => False 2024-11-20 07:47:52,024 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Bazel-remote Probing e1cbd7c596549eb95188426ded560fdb => False 2024-11-20 07:47:52,024 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(Jjvp1LHaV0kfbr3O6A9_MQ) to Bazel-remote 2024-11-20 07:47:52,026 DEBUG (yalibrary.runner.tasks.result) [Worker-011] Result node Jjvp1LHaV0kfbr3O6A9_MQ processed successfully: True 2024-11-20 07:47:52,027 DEBUG (root) [Worker-136] Run node cJ7sZvNFRnleQdaDGmIR9g in build root /home/runner/.ya/build/build_root/rjvy/000008 2024-11-20 07:47:52,029 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/e45ed026e851f01cdebe887550f5bec44af0e1c1c8931f1ac269a8e85ba18139 HTTP/1.1" 200 0 2024-11-20 07:47:52,034 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/5d9b6e6c0d2a029056be0bc6890a595823a22675355a8a2185d98e6c3d70e5d3 HTTP/1.1" 200 0 2024-11-20 07:47:52,043 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/524de23555f7d5d69908c2ecb6fce5ff25d3fc883471d99fd6e2a0fada321718 HTTP/1.1" 200 0 2024-11-20 07:47:52,045 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /ac/7035496c73476c304e38335633747a516a784266797700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:52,045 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(p5IlsGl0N83V3tzQjxBfyw) size=1092285 to Bazel-remote completed 2024-11-20 07:47:52,050 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/ef86fabdd9f7c3938b01c610c16adeca32e120a3bb7f77e077098cab5acacad4 HTTP/1.1" 200 0 2024-11-20 07:47:52,053 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /ac/4a6a7670314c486156306b666272334f3641395f4d5100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:52,053 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(Jjvp1LHaV0kfbr3O6A9_MQ) size=1092281 to Bazel-remote completed 2024-11-20 07:47:56,954 DEBUG (yalibrary.toolscache) [Worker-022] AC cache attempt: (put uid uboqtfQSg2JztPtU0OKhBg) 2024-11-20 07:47:56,955 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "HEAD /ac/75626f717466515367324a7a74507455304f4b68426700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:56,955 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-104] Bazel-remote Probing uboqtfQSg2JztPtU0OKhBg => False 2024-11-20 07:47:56,956 DEBUG (yalibrary.toolscache) [Worker-022] AC cache attempt: (put uid 49aa0635146afbb455c2f39201eed677) 2024-11-20 07:47:56,956 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-104] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(uboqtfQSg2JztPtU0OKhBg) to Bazel-remote 2024-11-20 07:47:56,958 DEBUG (yalibrary.runner.tasks.result) [Worker-019] Result node uboqtfQSg2JztPtU0OKhBg processed successfully: True 2024-11-20 07:47:56,960 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "PUT /cas/d9af52d28a0a575dc7b781a40bfb4fe63f5278c98dfb73e54f990e77b046a491 HTTP/1.1" 200 0 2024-11-20 07:47:56,964 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "PUT /cas/6264c6bca1d96fc3eddf7953e183805d5b915dc214787577074f100635fa3e56 HTTP/1.1" 200 0 2024-11-20 07:47:56,997 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "PUT /cas/c2e7ae1bae21f92db7bf3e38917728ac77419f462209644967d350437cff8248 HTTP/1.1" 200 0 2024-11-20 07:47:57,001 DEBUG (urllib3.connectionpool) [Worker-104] http://cachesrv.internal:8081 "PUT /ac/75626f717466515367324a7a74507455304f4b68426700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:57,001 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-104] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(uboqtfQSg2JztPtU0OKhBg) size=2779911 to Bazel-remote completed 2024-11-20 07:47:57,110 DEBUG (yalibrary.toolscache) [Worker-032] AC cache attempt: (put uid cJ7sZvNFRnleQdaDGmIR9g) 2024-11-20 07:47:57,111 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "HEAD /ac/634a37735a764e46526e6c6551646144476d4952396700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:57,112 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Bazel-remote Probing cJ7sZvNFRnleQdaDGmIR9g => False 2024-11-20 07:47:57,113 DEBUG (yalibrary.toolscache) [Worker-032] AC cache attempt: (put uid e1cbd7c596549eb95188426ded560fdb) 2024-11-20 07:47:57,114 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(cJ7sZvNFRnleQdaDGmIR9g) to Bazel-remote 2024-11-20 07:47:57,116 DEBUG (yalibrary.runner.tasks.result) [Worker-029] Result node cJ7sZvNFRnleQdaDGmIR9g processed successfully: True 2024-11-20 07:47:57,119 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/502aa0cad27b7c80ac62798c47fe08d37896264efbe76234acf59f9e40752295 HTTP/1.1" 200 0 2024-11-20 07:47:57,122 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/3ac9c2abb35fb0542ad4b8925255a892b9b11d07d67bffbeec89ebc6b7e387e6 HTTP/1.1" 200 0 2024-11-20 07:47:57,153 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/d4aac637e598b19f01b7adbf282bda1daf30919ef27a50532ecef1740017ae85 HTTP/1.1" 200 0 2024-11-20 07:47:57,156 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /ac/634a37735a764e46526e6c6551646144476d4952396700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:57,156 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(cJ7sZvNFRnleQdaDGmIR9g) size=2779888 to Bazel-remote completed 2024-11-20 07:47:57,528 DEBUG (yalibrary.toolscache) [Worker-017] AC cache attempt: (put uid l5HobHmRR0qhvUR7kH_Dhw) 2024-11-20 07:47:57,528 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "HEAD /ac/6c35486f62486d5252307168765552376b485f44687700000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:47:57,529 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Bazel-remote Probing l5HobHmRR0qhvUR7kH_Dhw => False 2024-11-20 07:47:57,529 DEBUG (yalibrary.toolscache) [Worker-017] AC cache attempt: (put uid 1b8303227b3bef9ad334e293138e6dde) 2024-11-20 07:47:57,530 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(l5HobHmRR0qhvUR7kH_Dhw) to Bazel-remote 2024-11-20 07:47:57,531 DEBUG (yalibrary.runner.tasks.result) [Worker-040] Result node l5HobHmRR0qhvUR7kH_Dhw processed successfully: True 2024-11-20 07:47:57,534 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/76d01bb2c393ae151f7dbd21c39085ff7f20248aa70cefbf177e5558194b0189 HTTP/1.1" 200 0 2024-11-20 07:47:57,538 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/5f5ac899a0b897b020aa0d9d01fd5b87ef6e036b9f8d9eb1e9e518230a6f750f HTTP/1.1" 200 0 2024-11-20 07:47:57,570 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/58d65e979fcd07c005a7eadfbc2b735eebabd83da5627618cc544fd693df1989 HTTP/1.1" 200 0 2024-11-20 07:47:57,574 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /ac/6c35486f62486d5252307168765552376b485f44687700000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:47:57,574 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(l5HobHmRR0qhvUR7kH_Dhw) size=2779887 to Bazel-remote completed 2024-11-20 07:48:08,526 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid ABlcTbQNzs_ipeapfi9cOA) 2024-11-20 07:48:08,526 DEBUG (yalibrary.toolscache) [Worker-050] AC cache attempt: (get uid d7d15f28504b991c7773d96b1d7beafe) 2024-11-20 07:48:08,527 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/41426c635462514e7a735f6970656170666939634f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:48:08,527 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid 6b14167e5e67975e4ece4606f1e3d883) 2024-11-20 07:48:08,527 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing ABlcTbQNzs_ipeapfi9cOA => False 2024-11-20 07:48:08,529 DEBUG (urllib3.connectionpool) [Worker-050] http://cachesrv.internal:8081 "HEAD /ac/c321f8203ccda4764abe291a5790f6bf2f1e436c79fb91b4219122d44619d611 HTTP/1.1" 404 0 2024-11-20 07:48:08,529 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-050] Bazel-remote Probing d7d15f28504b991c7773d96b1d7beafe => False 2024-11-20 07:48:08,529 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/docs/preprocessed.tar.gz(ABlcTbQNzs_ipeapfi9cOA) to Bazel-remote 2024-11-20 07:48:08,531 DEBUG (yalibrary.runner.tasks.result) [Worker-021] Result node ABlcTbQNzs_ipeapfi9cOA processed successfully: True 2024-11-20 07:48:08,531 DEBUG (root) [Worker-050] Run node 1SpcHsNSJr4f_HlyqW482Q in build root /home/runner/.ya/build/build_root/rjvy/00000f 2024-11-20 07:48:08,534 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/768891c76d912ed5339f3c44862d2e50af3f7a2417e88fadd62bb73888cb8379 HTTP/1.1" 200 0 2024-11-20 07:48:08,538 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/c508a8d366190c808cf752be24aa30df9bc04065e7d43bdae55626afb38c0a67 HTTP/1.1" 200 0 2024-11-20 07:48:08,985 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/211963e8f5d99dd8bc25a7e2e10c214efa7d66770d51749adbfb32abd8d3acbc HTTP/1.1" 200 0 2024-11-20 07:48:08,988 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /ac/41426c635462514e7a735f6970656170666939634f4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:48:08,988 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/docs/preprocessed.tar.gz(ABlcTbQNzs_ipeapfi9cOA) size=36209497 to Bazel-remote completed 2024-11-20 07:48:52,649 DEBUG (yalibrary.toolscache) [Worker-016] AC cache attempt: (put uid 1SpcHsNSJr4f_HlyqW482Q) 2024-11-20 07:48:52,650 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "HEAD /ac/3153706348734e534a7234665f486c7971573438325100000000000000000001 HTTP/1.1" 404 0 2024-11-20 07:48:52,650 DEBUG (yalibrary.runner.tasks.result) [Worker-050] Result node 1SpcHsNSJr4f_HlyqW482Q processed successfully: True 2024-11-20 07:48:52,650 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-064] Bazel-remote Probing 1SpcHsNSJr4f_HlyqW482Q => False 2024-11-20 07:48:52,651 DEBUG (yalibrary.toolscache) [Worker-016] AC cache attempt: (put uid d7d15f28504b991c7773d96b1d7beafe) 2024-11-20 07:48:52,651 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-064] Put ydb/docs/ydb-docs.tar.gz(1SpcHsNSJr4f_HlyqW482Q) to Bazel-remote 2024-11-20 07:48:52,655 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "PUT /cas/2f24b35efd63dd28dc59fff5e4fb1e8dd3192d55a0ebe4da005ed8b5dd178d1d HTTP/1.1" 200 0 2024-11-20 07:48:52,659 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "PUT /cas/866c5e9b022b48c32bdc436174ff98532dc4a350874e578cd963ac1759df39cd HTTP/1.1" 200 0 2024-11-20 07:48:53,016 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "PUT /cas/0283cb7c276209f18a79a4338a6f4d9f4d5cdbb2afdfa0e18edd3afc4552c3b8 HTTP/1.1" 200 0 2024-11-20 07:48:53,020 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "PUT /ac/3153706348734e534a7234665f486c7971573438325100000000000000000001 HTTP/1.1" 200 0 2024-11-20 07:48:53,020 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-064] Put ydb/docs/ydb-docs.tar.gz(1SpcHsNSJr4f_HlyqW482Q) size=39679448 to Bazel-remote completed 2024-11-20 07:48:53,087 DEBUG (yalibrary.runner.build_root) [Worker-076] Build root /home/runner/.ya/build/build_root/rjvy created=25 left=12 2024-11-20 07:48:53,088 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/rjvy/STAMP 2024-11-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(najgsXOkQ8x2Tk8AkGP4Zw$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(p5IlsGl0N83V3tzQjxBfyw$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(Jjvp1LHaV0kfbr3O6A9_MQ$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(uboqtfQSg2JztPtU0OKhBg$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(cJ7sZvNFRnleQdaDGmIR9g$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(l5HobHmRR0qhvUR7kH_Dhw$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(ABlcTbQNzs_ipeapfi9cOA$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(1SpcHsNSJr4f_HlyqW482Q$(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-20 07:48:53,099 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-20 07:48:53,100 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.005210399627686,"qty":1},"prepare_all_nodes":{"sum":0.059656858444214,"qty":1},"compact_cache":{"sum":0.004441499710083,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.257606267929077,"qty":13},"prepare[DO]":{"sum":0.002660512924194,"qty":3},"put_in_cache[DO]":{"sum":0.055399656295776,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.49344277381897,"qty":1},"DO":{"sum":92.992996692657471,"qty":10},"write_through_caches[DO]":{"sum":0.04745888710022,"qty":10},"result[DO]":{"sum":0.051169872283936,"qty":10},"put_in_dist_cache[DO]":{"sum":2.233467817306519,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":7.958460569381714,"qty":1},"clean_build_root":{"sum":0.000281572341919,"qty":1}},"all":{"qty":75,"sum":104.162253379821777},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732088860.119730472564697,1732088868.078191041946411],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":72.995100021362305,"build_type":"relwithdebinfo","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-20 07:48:53,100 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-20 07:48:53,100 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732088933.1003537 2024-11-20 07:48:53,100 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732088933.1003537 2024-11-20 07:48:53,100 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732088933.1003537 2024-11-20 07:48:53,100 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732088933.1007986 2024-11-20 07:48:53,100 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732088933.1007986 2024-11-20 07:48:53,100 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732088933.1007986 2024-11-20 07:48:53,116 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-20 07:48:53,116 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-20 07:48:53,117 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,117 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,117 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-20 07:48:53,117 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-20 07:48:53,117 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-20 07:48:53,119 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-20 07:48:53,126 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,126 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,127 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,128 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 07:48:53,128 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732088933.1280587 2024-11-20 07:48:53,128 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732088933.1280587 2024-11-20 07:48:53,128 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732088933.1280587 2024-11-20 07:48:53,128 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-20 07:48:53,129 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-20 07:48:53,129 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-20 07:48:53,130 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168760399 2024-11-20 07:48:53,130 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732088933.130291 2024-11-20 07:48:53,130 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732088933.130291 2024-11-20 07:48:53,130 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732088933.130291 2024-11-20 07:48:53,130 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-20 07:48:53,131 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-20 07:48:53,131 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-20 07:48:53,131 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-20 07:48:53,131 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",653,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",20360,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",44093,"local"]] 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 72001 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 65106 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 65106 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-20 07:48:53,132 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-20 07:48:53,133 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 44093 2024-11-20 07:48:53,133 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-20 07:48:53,133 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-20 07:48:53,134 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-20 07:48:53,134 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-20 07:48:53,134 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 92732 2024-11-20 07:48:53,134 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732088933.1349635 2024-11-20 07:48:53,135 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732088933.1349635 2024-11-20 07:48:53,135 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732088933.1349635 2024-11-20 07:48:53,135 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732088933.1356385 2024-11-20 07:48:53,135 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732088933.1356385 2024-11-20 07:48:53,135 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732088933.1356385 2024-11-20 07:48:53,135 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-20 07:48:53,135 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 07:48:53,136 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-20 07:48:53,136 DEBUG (StoredReport) [MainThread] Finish report 2024-11-20 07:48:53,136 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 0.00010442733764648438}, 'BuildResultsListener': {'duration_sec': 0.0015683174133300781}} 2024-11-20 07:48:53,136 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-20 07:48:53,137 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-20 07:48:53,137 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 07:48:53,137 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 07:48:53,137 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 07:48:53,137 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 07:48:53,137 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 07:48:53,137 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 07:48:53,137 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 07:48:53,138 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-20 07:48:53,138 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-20 07:48:53,138 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-20 07:48:53,138 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732088933.1386256 2024-11-20 07:48:53,138 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732088933.1386256 2024-11-20 07:48:53,138 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732088933.1386256 2024-11-20 07:48:53,139 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-20 07:48:53,140 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732088933.14 2024-11-20 07:48:53,140 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732088933.14 2024-11-20 07:48:53,140 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732088933.14 2024-11-20 07:48:53,140 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732088933.1402416 2024-11-20 07:48:53,140 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732088933.1402416 2024-11-20 07:48:53,140 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732088933.1402416 2024-11-20 07:48:53,140 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732088933.1409674 2024-11-20 07:48:53,141 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732088933.1409674 2024-11-20 07:48:53,141 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732088933.1409674 2024-11-20 07:48:53,141 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732088933.1411471 2024-11-20 07:48:53,141 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732088933.1411471 2024-11-20 07:48:53,141 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732088933.1411471 2024-11-20 07:48:53,141 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732088933.141331 2024-11-20 07:48:53,141 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732088933.141331 2024-11-20 07:48:53,141 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732088933.141331 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000105 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000030 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000003 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000003 2024-11-20 07:48:53,141 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000019 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000003 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000002 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000002 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000008 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000001 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000004 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000009 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000017 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000008 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000002 2024-11-20 07:48:53,142 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000003 2024-11-20 07:48:53,142 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-20 07:48:53,143 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732088858.2950.21o0dlr4 2024-11-20 07:48:53,143 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-20 07:48:53,143 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000620 2024-11-20 07:48:53,143 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000012 2024-11-20 07:48:53,143 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000006 2024-11-20 07:48:53,143 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000006 2024-11-20 07:48:53,143 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000008 2024-11-20 07:48:53,144 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001004 2024-11-20 07:48:53,144 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000008 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000005 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000007 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000009 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000005 2024-11-20 07:48:53,145 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])