2024-11-20 14:56:18,341 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-20 14:56:18,342 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-20 14:56:18,342 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000814 2024-11-20 14:56:18,342 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 14:56:18,342 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 14:56:18,343 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 14:56:18,343 DEBUG (root) [MainThread] vcs type: git 2024-11-20 14:56:18,343 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000212 2024-11-20 14:56:18,343 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,343 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000415 2024-11-20 14:56:18,343 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-20 14:56:18,343 DEBUG (root) [MainThread] Start up timestamp 2024-11-20T14:56:18.UTC (2024-11-20T14:56:18.UTC) 2024-11-20 14:56:18,343 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000039 2024-11-20 14:56:18,343 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000006 2024-11-20 14:56:18,344 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000450 2024-11-20 14:56:18,344 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka instead of None 2024-11-20 14:56:18,344 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000145 2024-11-20 14:56:18,344 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732114578.344454 2024-11-20 14:56:18,344 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732114578.344454 2024-11-20 14:56:18,344 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732114578.344454 2024-11-20 14:56:18,344 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732114578.344959 2024-11-20 14:56:18,345 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732114578.344959 2024-11-20 14:56:18,345 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732114578.344959 2024-11-20 14:56:18,345 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-20 14:56:18,357 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-20 14:56:18,358 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-20 14:56:18,358 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-20 14:56:18,358 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 14:56:18,358 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-20 14:56:18,359 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-20 14:56:18,359 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-20 14:56:18,359 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-20 14:56:18,359 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 14:56:18,359 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 14:56:18,360 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-20 14:56:18,370 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 14:56:18,370 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 14:56:18,371 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732114578.3717768 2024-11-20 14:56:18,371 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732114578.3717768 2024-11-20 14:56:18,371 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732114578.3717768 2024-11-20 14:56:18,371 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732114578.3719058 2024-11-20 14:56:18,371 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732114578.3719058 2024-11-20 14:56:18,371 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732114578.3719058 2024-11-20 14:56:18,372 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 14:56:18,372 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 14:56:18,372 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-20 14:56:18,372 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-20 14:56:18,372 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000286 2024-11-20 14:56:18,374 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,374 DEBUG (root) [MainThread] Update replacements in 2024-11-20 14:56:18,374 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 14:56:18,374 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,374 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 14:56:18,374 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,374 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002308 2024-11-20 14:56:18,375 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000230 2024-11-20 14:56:18,375 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000050 2024-11-20 14:56:18,375 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,376 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000929 2024-11-20 14:56:18,376 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000037 2024-11-20 14:56:18,376 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 14:56:18,376 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 14:56:18,376 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 14:56:18,376 DEBUG (root) [MainThread] vcs type: git 2024-11-20 14:56:18,376 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000228 2024-11-20 14:56:18,376 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-20 14:56:18,376 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-20 14:56:18,376 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000101 2024-11-20 14:56:18,376 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-20 14:56:18,377 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000078 2024-11-20 14:56:18,377 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-20 14:56:18,377 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000001 2024-11-20 14:56:18,378 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 14:56:18,378 DEBUG (root) [MainThread] Update replacements in 2024-11-20 14:56:18,378 DEBUG (root) [MainThread] Update replacements in 2024-11-20 14:56:18,378 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001105 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000080 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000141 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-20 14:56:18,378 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000038 2024-11-20 14:56:18,378 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-20 14:56:18,379 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000272 2024-11-20 14:56:18,379 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-20 14:56:18,379 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000420 2024-11-20 14:56:18,420 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.040406 2024-11-20 14:56:18,420 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-20 14:56:18,420 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732114578.4207976 2024-11-20 14:56:18,420 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732114578.4207976 2024-11-20 14:56:18,420 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732114578.4207976 2024-11-20 14:56:18,420 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732114578.4209557 2024-11-20 14:56:18,421 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732114578.4209557 2024-11-20 14:56:18,421 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732114578.4209557 2024-11-20 14:56:18,549 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732114578.5491881 2024-11-20 14:56:18,549 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732114578.5491881 2024-11-20 14:56:18,549 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732114578.5491881 2024-11-20 14:56:18,549 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732114578.5494287 2024-11-20 14:56:18,549 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732114578.5494287 2024-11-20 14:56:18,549 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732114578.5494287 2024-11-20 14:56:18,549 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732114578.549552 2024-11-20 14:56:18,549 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732114578.549552 2024-11-20 14:56:18,549 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732114578.549552 2024-11-20 14:56:18,549 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-20 14:56:18,549 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-20 14:56:18,549 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-20 14:56:18,549 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732114578.5497584 2024-11-20 14:56:18,549 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732114578.5497584 2024-11-20 14:56:18,549 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732114578.5497584 2024-11-20 14:56:18,550 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732114578.5500054 2024-11-20 14:56:18,550 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732114578.5500054 2024-11-20 14:56:18,550 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732114578.5500054 2024-11-20 14:56:18,552 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-20 14:56:18,552 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 14:56:18,552 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-20 14:56:18,554 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.CPo37CQCHP' file to obtain bazel remote password 2024-11-20 14:56:18,554 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732114578.5548565 2024-11-20 14:56:18,555 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732114578.5548565 2024-11-20 14:56:18,555 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732114578.5548565 2024-11-20 14:56:18,555 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-20 14:56:18,555 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-20 14:56:18,556 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 14:56:18,556 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 14:56:18,557 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 14:56:18,557 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-20 14:56:18,559 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-20 14:56:18,561 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 14:56:18,561 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 14:56:18,561 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 14:56:18,561 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-20 14:56:18,561 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-20 14:56:18,562 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 14:56:18,562 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 14:56:18,562 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 14:56:18,562 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-20 14:56:18,563 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-20 14:56:18,572 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-20 14:56:18,572 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-20 14:56:18,572 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 14:56:18,573 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 14:56:18,573 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 14:56:18,573 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-20 14:56:18,573 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 14:56:18,573 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 14:56:18,573 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-20 14:56:18,573 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-20 14:56:18,573 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 14:56:18,573 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 14:56:18,573 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 14:56:18,577 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2282999, 312029, 'unix:/home/runner/.ya/tools/v4/psingxRHo75.tmp') 2024-11-20 14:56:18,582 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62124032 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 14:56:18,582 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-20 14:56:18,582 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-20 14:56:18,582 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.02071380615234375 2024-11-20 14:56:18,582 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114578.58286 2024-11-20 14:56:18,583 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_started=1732114578.58286 2024-11-20 14:56:18,583 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_started - 1732114578.58286 2024-11-20 14:56:18,583 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114578.5832717 2024-11-20 14:56:18,583 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started=1732114578.5832717 2024-11-20 14:56:18,583 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started - 1732114578.5832717 2024-11-20 14:56:18,584 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732114578.5833926 2024-11-20 14:56:18,584 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732114578.5833926 2024-11-20 14:56:18,584 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732114578.5833926 2024-11-20 14:56:18,584 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732114578.5842288 2024-11-20 14:56:18,584 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732114578.5842288 2024-11-20 14:56:18,584 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732114578.5842288 2024-11-20 14:56:18,584 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114578.5844266 2024-11-20 14:56:18,584 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started=1732114578.5844266 2024-11-20 14:56:18,584 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started - 1732114578.5844266 2024-11-20 14:56:18,584 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-20 14:56:18,584 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114578.5845573 2024-11-20 14:56:18,585 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started=1732114578.5845573 2024-11-20 14:56:18,585 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started - 1732114578.5845573 2024-11-20 14:56:18,585 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-20 14:56:18,587 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 14:56:18,589 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b"fatal: no tag exactly matches 'b182a30c97b7b026bc6e5b5aa2924c84703aa2d9'\n" Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-20 14:56:18,592 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'b182a30c97b7b026bc6e5b5aa2924c84703aa2d9', author:b'Mark Ziganshin ', summary:b'Merge 87ff8c6fa096d58f5041100bf2890fc8c0c6d422 into dd01e456bccfc62249474d734efb54aeec53f28a', svn_id:b'' 2024-11-20 14:56:18,592 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-3yxfmycuty Host information: Linux ghrun-3yxfmycuty 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-20 14:56:18,593 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"b182a30c97b7b026bc6e5b5aa2924c84703aa2d9\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"Mark Ziganshin \",\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-20T14:56:18.000000Z\",\n \"BUILD_HOST\": \"ghrun-3yxfmycuty\",\n \"BUILD_TIMESTAMP\": 1732114578,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: b182a30c97b7b026bc6e5b5aa2924c84703aa2d9\\n Branch: \\n Author: Mark Ziganshin \\n Summary: Merge 87ff8c6fa096d58f5041100bf2890fc8c0c6d422 into dd01e456bccfc62249474d734efb54aeec53f28a\\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-3yxfmycuty\\n Host information: \\n Linux ghrun-3yxfmycuty 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: b182a30c97b7b026bc6e5b5aa2924c84703aa2d9\\n Branch: \\n Author: Mark Ziganshin \\n Summary: Merge 87ff8c6fa096d58f5041100bf2890fc8c0c6d422 into dd01e456bccfc62249474d734efb54aeec53f28a\\n\",\n \"VCS\": \"git\"\n}" 2024-11-20 14:56:18,593 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.03736424446105957 2024-11-20 14:56:18,620 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 14:56:18,621 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 14:56:18,755 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 14:56:18,755 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-20 14:56:18,755 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114578.755516 2024-11-20 14:56:18,755 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished=1732114578.755516 2024-11-20 14:56:18,755 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732114578.755516 2024-11-20 14:56:18,755 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114578.755725 2024-11-20 14:56:18,755 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started=1732114578.755725 2024-11-20 14:56:18,755 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started - 1732114578.755725 2024-11-20 14:56:18,755 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 0x7f23cb76da80>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/makefiles.dart'} 2024-11-20 14:56:18,756 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-20 14:56:18,756 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-20 14:56:18,756 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 14:56:18,756 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-20 14:56:18,756 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/1732114578.2283564.kf4ijaka/yatmpsogjuka9 --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/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 14:56:18,757 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 14:56:18,760 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-20 14:56:18,760 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114578.760553 2024-11-20 14:56:18,760 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished=1732114578.760553 2024-11-20 14:56:18,760 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished - 1732114578.760553 2024-11-20 14:56:18,760 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114578.7608523 2024-11-20 14:56:18,760 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started=1732114578.7608523 2024-11-20 14:56:18,760 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started - 1732114578.7608523 2024-11-20 14:56:18,761 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 0x7f23caea3920>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/makefiles.dart'} 2024-11-20 14:56:18,761 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-20 14:56:18,761 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-20 14:56:18,761 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-20 14:56:18,761 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/1732114578.2283564.kf4ijaka/yatmpfj1u4zql --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/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 14:56:18,765 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732114578765564,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114578765676,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732114578765717,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114578765725,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732114578765730,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732114578766022,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:18,766 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732114578766027,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:18,768 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732114578768499,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 14:56:18,768 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114578768597,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:18,768 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732114578768630,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:18,769 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114578768638,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:18,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732114578768642,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:18,769 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732114578768901,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:18,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732114578768905,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:18,922 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732114578922278,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:18,922 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732114578922302,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:18,923 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732114578923741,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:18,923 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732114578923754,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:18,925 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732114578924964,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:18,925 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732114578924971,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:18,925 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732114578925569,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:18,925 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732114578925588,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:18,927 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732114578927025,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:18,927 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732114578927038,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:18,928 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732114578928266,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:18,928 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732114578928275,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,043 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732114579043121,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,046 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732114579046527,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579073913,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579073932,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579073937,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074057,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732114579074062,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,074 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074123,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074127,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074131,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074135,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074139,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074142,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,075 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074146,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579074149,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732114579075022,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579075031,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732114579075040,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732114579075044,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732114579075049,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579075055,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732114579075060,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732114579075064,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-20 14:56:19,076 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732114579075068,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,077 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077306,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,077 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077325,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,077 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077330,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077419,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732114579077423,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077463,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077468,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077472,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077476,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077480,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077484,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,078 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077487,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579077490,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732114579078350,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579078358,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732114579078365,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732114579078369,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732114579078373,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579078378,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732114579078382,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732114579078385,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-20 14:56:19,079 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732114579078389,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,181 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.1058084814,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732114579180886,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,181 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.1024338278,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732114579180838,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,181 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732114579180909,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,181 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732114579180862,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,181 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579180917,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579180872,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579180924,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579180879,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732114579180971,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732114579180930,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,182 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":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579181046,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,182 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":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579181006,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732114579181050,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732114579181010,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9022092; load time = 12420; loaded MD5 time = 760; Max loaded MD5 time = 14; loaded count = 2174; max load time = 27; mapped size = 40632376; mapped MD5 time = 12520; Max mapped MD5 time = 816; mapped count = 130; map time = 839; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3712; lstat min us = 1; lstat avr us = 1; lstat max us = 25; 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 = 2523; readdir min us = 0; readdir avr us = 0; readdir max us = 39; (opendir + readdir) sum us = 2523; (lstat + opendir + readdir) sum us = 6235;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732114579181082,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9022092; load time = 11323; loaded MD5 time = 776; Max loaded MD5 time = 11; loaded count = 2174; max load time = 27; mapped size = 40632376; mapped MD5 time = 11555; Max mapped MD5 time = 814; mapped count = 130; map time = 777; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3164; lstat min us = 1; lstat avr us = 1; lstat max us = 21; opendir count = 388; opendir sum us = 0; opendir min us = 0; opendir avr us = 0; opendir max us = 0; readdir count = 2837; readdir sum us = 2051; readdir min us = 0; readdir avr us = 0; readdir max us = 36; (opendir + readdir) sum us = 2051; (lstat + opendir + readdir) sum us = 5215;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732114579181046,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2523,"LoadTime":12420,"LoadedCount":2174,"LoadedMD5Time":760,"LoadedSize":9022092,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":6235,"LstatMaxUs":25,"LstatMinUs":1,"LstatSumUs":3712,"MapTime":839,"MappedCount":130,"MappedMD5Time":12520,"MappedSize":40632376,"MaxLoadTime":27,"MaxLoadedMD5Time":14,"MaxMappedMD5Time":816,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":39,"ReaddirMinUs":0,"ReaddirSumUs":2523,"_timestamp":1732114579181094,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2051,"LoadTime":11323,"LoadedCount":2174,"LoadedMD5Time":776,"LoadedSize":9022092,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5215,"LstatMaxUs":21,"LstatMinUs":1,"LstatSumUs":3164,"MapTime":777,"MappedCount":130,"MappedMD5Time":11555,"MappedSize":40632376,"MaxLoadTime":27,"MaxLoadedMD5Time":11,"MaxMappedMD5Time":814,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":36,"ReaddirMinUs":0,"ReaddirSumUs":2051,"_timestamp":1732114579181059,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9022092,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732114579181106,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9022092,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732114579181070,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":12420,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732114579181109,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,183 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":11323,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732114579181074,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732114579181112,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732114579181077,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732114579181114,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732114579181079,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":839,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732114579181117,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":777,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732114579181082,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732114579181120,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732114579181085,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732114579181122,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732114579181087,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,184 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9022092; load time = 12420; loaded count = 2174; max load time = 27;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732114579181130,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9022092; load time = 11323; loaded count = 2174; max load time = 27;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732114579181095,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":12420,"LoadedCount":2174,"LoadedSize":9022092,"MaxLoadTime":27,"_timestamp":1732114579181134,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":11323,"LoadedCount":2174,"LoadedSize":9022092,"MaxLoadTime":27,"_timestamp":1732114579181099,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-20 14:56:19,185 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":1732114579181144,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,185 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":1732114579181110,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732114579181149,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732114579181115,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-20 14:56:19,185 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":1732114579181162,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,185 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":1732114579181128,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,185 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732114579181166,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732114579181132,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-20 14:56:19,186 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":1732114579181181,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,186 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":1732114579181146,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732114579181184,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732114579181150,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732114579181189,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732114579181155,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732114579181192,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-20 14:56:19,186 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732114579181158,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-20 14:56:19,187 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":1732114579181199,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,187 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":1732114579181166,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732114579181203,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732114579181170,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732114579181206,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732114579181173,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,187 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":1732114579181298,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,187 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":1732114579181268,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732114579181302,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732114579181272,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-20 14:56:19,187 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732114579181306,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732114579181276,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732114579181309,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732114579181279,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732114579181312,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732114579181281,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732114579181314,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732114579181284,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732114579181323,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732114579181293,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,188 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732114579181882,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732114579181860,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732114579181884,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732114579181862,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732114579184387,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732114579184374,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732114579184390,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732114579184377,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732114579184437,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,189 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732114579184425,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,190 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-relwithdebinfo-nopic). Tool targets: set() 2024-11-20 14:56:19,190 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732114579184439,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,190 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-relwithdebinfo-pic). Tool targets: set() 2024-11-20 14:56:19,190 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732114579184428,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,190 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732114579.1904366 2024-11-20 14:56:19,190 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732114579.1904366 2024-11-20 14:56:19,190 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732114579.1904366 2024-11-20 14:56:19,190 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-20 14:56:19,190 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732114579.1908576 2024-11-20 14:56:19,190 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732114579.1908576 2024-11-20 14:56:19,190 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732114579.1908576 2024-11-20 14:56:19,191 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732114579.1910582 2024-11-20 14:56:19,191 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732114579.1910582 2024-11-20 14:56:19,191 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732114579.1910582 2024-11-20 14:56:19,191 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732114579.1912582 2024-11-20 14:56:19,191 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732114579.1912582 2024-11-20 14:56:19,191 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732114579.1912582 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732114579184441,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732114579184430,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732114579184443,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732114579184432,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732114579184445,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,191 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732114579184435,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732114579184447,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732114579184437,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732114579184500,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732114579184490,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732114579184503,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,192 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732114579184493,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,192 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":1732114579185379,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,192 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":1732114579185390,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,192 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":1732114579185389,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-20 14:56:19,192 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":1732114579185401,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579185396,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579185407,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579185401,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579185412,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732114579186656,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732114579186565,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732114579186662,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732114579186571,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732114579187078,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732114579186982,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-20 14:56:19,193 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732114579187109,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732114579187013,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732114579187112,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732114579187016,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732114579187547,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732114579187450,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579187662,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579187569,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732114579187666,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732114579187573,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732114579187668,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,194 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732114579187575,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579187671,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579187579,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732114579187704,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732114579187612,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732114579187706,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732114579187614,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732114579187710,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732114579187618,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732114579187855,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,195 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732114579187765,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732114579187877,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732114579187790,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732114579188564,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732114579188469,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732114579188620,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732114579188527,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732114579188628,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732114579188533,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579188632,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579188538,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,196 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732114579188635,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732114579188542,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732114579191597,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732114579191673,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732114579191602,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732114579191683,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579191614,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,197 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579191695,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,199 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":1732114579198893,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,199 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":1732114579198958,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,199 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":1732114579198901,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-20 14:56:19,199 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":1732114579198965,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-20 14:56:19,199 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579198909,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,199 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579198973,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,199 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.007312103896,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732114579198918,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,199 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.007295630376,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732114579198983,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,199 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732114579198923,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732114579198987,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732114579198926,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732114579198990,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579199680,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579199757,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732114579199686,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732114579199762,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732114579199697,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-20 14:56:19,200 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732114579199775,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-20 14:56:19,200 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":1732114579199709,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,200 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":1732114579199788,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,201 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":1732114579199715,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-20 14:56:19,201 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":1732114579199794,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-20 14:56:19,201 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.0111680318,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732114579199799,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,202 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01133823403,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732114579199875,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,202 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732114579199804,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,202 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732114579199879,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732114579199826,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732114579199901,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732114579199829,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732114579199904,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,203 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":1732114579199837,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,203 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":1732114579199912,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732114579199842,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732114579199916,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579203462,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,203 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579203467,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579203473,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579203479,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732114579203478,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732114579203484,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579203490,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579203496,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":2283667,"Sub":"","Type":"Debug","_timestamp":1732114579203500,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":2283669,"Sub":"","Type":"Debug","_timestamp":1732114579203508,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732114579204166,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 14:56:19,204 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732114579204165,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 14:56:19,205 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4385980586,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732114579204174,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 14:56:19,205 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.435652984,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732114579204174,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 14:56:19,205 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732114579204182,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 14:56:19,205 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732114579204180,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 14:56:19,211 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/1732114578.2283564.kf4ijaka/yatmpfj1u4zql --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/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 14:56:19,211 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/1732114578.2283564.kf4ijaka/yatmpsogjuka9 --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/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 14:56:19,212 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732114578.761292219161987,"finish":1732114578.761829614639282,"duration":0.000537395477295},"execution":{"start":1732114578.761830568313599,"finish":1732114579.211315631866455,"duration":0.449485063552856},"postprocessing":{"start":1732114579.211317777633667,"finish":1732114579.211491346359253,"duration":0.000173568725586}},"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":1732114578.76849889755249,"finish":1732114579.204180002212524,"duration":0.435681104660034},"Load configuration from cache":{"start":1732114578.768630027770996,"finish":1732114578.768641948699951,"duration":0.000011920928955},"Load configuration (no cache)":{"start":1732114578.7689049243927,"finish":1732114578.9255690574646,"duration":0.156664133071899},"Compile and RecalcAll conditions":{"start":1732114578.925587892532349,"finish":1732114578.927025079727173,"duration":0.001437187194824},"Fold global commands":{"start":1732114578.927037954330444,"finish":1732114578.928266048431396,"duration":0.001228094100952},"Load plugins":{"start":1732114578.928275108337402,"finish":1732114579.046526908874512,"duration":0.118251800537109},"ymake main":{"start":1732114579.077423095703125,"finish":1732114579.204164981842041,"duration":0.126741886138916},"Build graph":{"start":1732114579.078372955322266,"finish":1732114579.181173086166382,"duration":0.102800130844116},"Configure graph":{"start":1732114579.078388929367065,"finish":1732114579.180861949920654,"duration":0.102473020553589},"Find Lost Includes":{"start":1732114579.180929899215698,"finish":1732114579.181010007858276,"duration":0.000080108642578},"Set reachable nodes":{"start":1732114579.18129301071167,"finish":1732114579.181859970092773,"duration":0.000566959381104},"Sort edges":{"start":1732114579.181862115859985,"finish":1732114579.184374094009399,"duration":0.002511978149414},"Report Foreign Platform Events":{"start":1732114579.184376955032349,"finish":1732114579.184425115585327,"duration":0.000048160552979},"Check blacklist":{"start":1732114579.184427976608276,"finish":1732114579.184429883956909,"duration":0.000001907348633},"Check isolated projects":{"start":1732114579.184432029724121,"finish":1732114579.18443489074707,"duration":0.000002861022949},"Report Configure Events":{"start":1732114579.184437036514282,"finish":1732114579.184489965438843,"duration":0.000052928924561},"Save and compact":{"start":1732114579.184493064880371,"finish":1732114579.186564922332764,"duration":0.002071857452393},"Detect loops":{"start":1732114579.186570882797241,"finish":1732114579.187012910842896,"duration":0.000442028045654},"Apply Dependency Management":{"start":1732114579.187016010284424,"finish":1732114579.187575101852417,"duration":0.000559091567993},"Save Dependency management cache":{"start":1732114579.187449932098389,"finish":1732114579.187572956085205,"duration":0.000123023986816},"Fill package outputs":{"start":1732114579.187612056732178,"finish":1732114579.187613964080811,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732114579.187618017196655,"finish":1732114579.187764883041382,"duration":0.000146865844727},"Find missing peerdirs":{"start":1732114579.187789916992188,"finish":1732114579.188468933105469,"duration":0.000679016113281},"Export JSON":{"start":1732114579.18852710723877,"finish":1732114579.199901103973389,"duration":0.011373996734619},"Write JSON":{"start":1732114579.188533067703247,"finish":1732114579.199878931045532,"duration":0.011345863342285},"Visit JSON":{"start":1732114579.188541889190674,"finish":1732114579.191673040390015,"duration":0.003131151199341},"Render JSON":{"start":1732114579.191683053970337,"finish":1732114579.198987007141113,"duration":0.007303953170776},"Save Uids cache":{"start":1732114579.198990106582642,"finish":1732114579.199762105941772,"duration":0.000771999359131}},"metrics":{"File access":{"loaded size":9022092,"load time":11323,"loaded MD5 time":776,"Max loaded MD5 time":11,"loaded count":2174,"max load time":27,"mapped size":40632376,"mapped MD5 time":11555,"Max mapped MD5 time":814,"mapped count":130,"map time":777,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3164,"lstat min us":1,"lstat avr us":1,"lstat max us":21,"opendir count":388,"opendir sum us":0,"opendir min us":0,"opendir avr us":0,"opendir max us":0,"readdir count":2837,"readdir sum us":2051,"readdir min us":0,"readdir avr us":0,"readdir max us":36,"(opendir + readdir) sum us":2051,"(lstat + opendir + readdir) sum us":5215},"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/1732114578.2283564.kf4ijaka/yatmpfj1u4zql","--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/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.gsiz2hx_/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.449485063552856} 2024-11-20 14:56:19,212 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732114579.2123346 2024-11-20 14:56:19,212 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732114579.2123346 2024-11-20 14:56:19,212 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732114579.2123346 2024-11-20 14:56:19,212 DEBUG (core.event_handling.base_subscriber) [Misc-03] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 14:56:19,213 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732114578.756195068359375,"finish":1732114578.756747484207153,"duration":0.000552415847778},"execution":{"start":1732114578.756747961044312,"finish":1732114579.21198844909668,"duration":0.455240488052368},"postprocessing":{"start":1732114579.211991548538208,"finish":1732114579.212737560272217,"duration":0.000746011734009}},"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":1732114578.76556396484375,"finish":1732114579.204181909561157,"duration":0.438617944717407},"Load configuration from cache":{"start":1732114578.765717029571533,"finish":1732114578.765729904174805,"duration":0.000012874603271},"Load configuration (no cache)":{"start":1732114578.766026973724365,"finish":1732114578.922277927398682,"duration":0.156250953674316},"Compile and RecalcAll conditions":{"start":1732114578.922302007675171,"finish":1732114578.923741102218628,"duration":0.001439094543457},"Fold global commands":{"start":1732114578.923753976821899,"finish":1732114578.92496395111084,"duration":0.00120997428894},"Load plugins":{"start":1732114578.924971103668213,"finish":1732114579.043121099472046,"duration":0.118149995803833},"ymake main":{"start":1732114579.07406210899353,"finish":1732114579.204165935516357,"duration":0.130103826522827},"Build graph":{"start":1732114579.075048923492432,"finish":1732114579.181205987930298,"duration":0.106157064437866},"Configure graph":{"start":1732114579.07506799697876,"finish":1732114579.180908918380737,"duration":0.105840921401978},"Find Lost Includes":{"start":1732114579.180970907211304,"finish":1732114579.181050062179565,"duration":0.000079154968262},"Set reachable nodes":{"start":1732114579.181323051452637,"finish":1732114579.181881904602051,"duration":0.000558853149414},"Sort edges":{"start":1732114579.181884050369263,"finish":1732114579.184386968612671,"duration":0.002502918243408},"Report Foreign Platform Events":{"start":1732114579.184390068054199,"finish":1732114579.184437036514282,"duration":0.000046968460083},"Check blacklist":{"start":1732114579.184438943862915,"finish":1732114579.184441089630127,"duration":0.000002145767212},"Check isolated projects":{"start":1732114579.18444299697876,"finish":1732114579.184444904327393,"duration":0.000001907348633},"Report Configure Events":{"start":1732114579.184447050094604,"finish":1732114579.184499979019165,"duration":0.000052928924561},"Save and compact":{"start":1732114579.184503078460693,"finish":1732114579.18665599822998,"duration":0.002152919769287},"Detect loops":{"start":1732114579.186661958694458,"finish":1732114579.187108993530273,"duration":0.000447034835815},"Apply Dependency Management":{"start":1732114579.187112092971802,"finish":1732114579.187668085098267,"duration":0.000555992126465},"Save Dependency management cache":{"start":1732114579.187546968460083,"finish":1732114579.187665939331055,"duration":0.000118970870972},"Fill package outputs":{"start":1732114579.187704086303711,"finish":1732114579.187705993652344,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732114579.187710046768188,"finish":1732114579.187855005264282,"duration":0.000144958496094},"Find missing peerdirs":{"start":1732114579.18787693977356,"finish":1732114579.18856406211853,"duration":0.000687122344971},"Export JSON":{"start":1732114579.188620090484619,"finish":1732114579.199826002120972,"duration":0.011205911636353},"Write JSON":{"start":1732114579.18862795829773,"finish":1732114579.199804067611694,"duration":0.011176109313965},"Visit JSON":{"start":1732114579.188635110855103,"finish":1732114579.191596984863281,"duration":0.002961874008179},"Render JSON":{"start":1732114579.191601991653442,"finish":1732114579.198923110961914,"duration":0.007321119308472},"Save Uids cache":{"start":1732114579.198925971984863,"finish":1732114579.199686050415039,"duration":0.000760078430176}},"metrics":{"File access":{"loaded size":9022092,"load time":12420,"loaded MD5 time":760,"Max loaded MD5 time":14,"loaded count":2174,"max load time":27,"mapped size":40632376,"mapped MD5 time":12520,"Max mapped MD5 time":816,"mapped count":130,"map time":839,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3712,"lstat min us":1,"lstat avr us":1,"lstat max us":25,"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":2523,"readdir min us":0,"readdir avr us":0,"readdir max us":39,"(opendir + readdir) sum us":2523,"(lstat + opendir + readdir) sum us":6235},"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/1732114578.2283564.kf4ijaka/yatmpsogjuka9","--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/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.qg4uvxf4/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.455240488052368} 2024-11-20 14:56:19,213 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732114579.213292 2024-11-20 14:56:19,213 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732114579.213292 2024-11-20 14:56:19,213 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732114579.213292 2024-11-20 14:56:19,213 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732114579.213473 2024-11-20 14:56:19,213 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732114579.213473 2024-11-20 14:56:19,213 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732114579.213473 2024-11-20 14:56:19,213 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114579.2137167 2024-11-20 14:56:19,213 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished=1732114579.2137167 2024-11-20 14:56:19,213 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished - 1732114579.2137167 2024-11-20 14:56:19,214 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114579.2140017 2024-11-20 14:56:19,214 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_started=1732114579.2140017 2024-11-20 14:56:19,214 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_started - 1732114579.2140017 2024-11-20 14:56:19,214 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732114579.2140594 2024-11-20 14:56:19,214 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732114579.2140594 2024-11-20 14:56:19,214 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732114579.2140594 2024-11-20 14:56:19,214 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114579.214476 2024-11-20 14:56:19,214 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished=1732114579.214476 2024-11-20 14:56:19,214 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732114579.214476 2024-11-20 14:56:19,214 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114579.2147439 2024-11-20 14:56:19,214 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started=1732114579.2147439 2024-11-20 14:56:19,214 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started - 1732114579.2147439 2024-11-20 14:56:19,219 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-20 14:56:19,219 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-20 14:56:19,219 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.0004248619079589844 2024-11-20 14:56:19,219 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.0002605915069580078 2024-11-20 14:56:19,219 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114579.2196784 2024-11-20 14:56:19,219 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished=1732114579.2196784 2024-11-20 14:56:19,219 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished - 1732114579.2196784 2024-11-20 14:56:19,219 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114579.2197843 2024-11-20 14:56:19,220 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished=1732114579.2197843 2024-11-20 14:56:19,220 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732114579.2197843 2024-11-20 14:56:19,220 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732114579.2202888 2024-11-20 14:56:19,220 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished=1732114579.2202888 2024-11-20 14:56:19,220 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished - 1732114579.2202888 2024-11-20 14:56:19,220 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732114579.2208052 2024-11-20 14:56:19,220 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished=1732114579.2208052 2024-11-20 14:56:19,220 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732114579.2208052 2024-11-20 14:56:19,221 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-relwithdebinfo: 0.029874086380004883 2024-11-20 14:56:19,221 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732114579.2212512 2024-11-20 14:56:19,221 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732114579.2212512 2024-11-20 14:56:19,221 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732114579.2212512 2024-11-20 14:56:19,221 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732114579.2214408 2024-11-20 14:56:19,221 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732114579.2214408 2024-11-20 14:56:19,221 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732114579.2214408 2024-11-20 14:56:19,221 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-relwithdebinfo: 0.0004951953887939453 2024-11-20 14:56:19,221 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732114579.2217188 2024-11-20 14:56:19,221 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732114579.2217188 2024-11-20 14:56:19,221 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732114579.2217188 2024-11-20 14:56:19,221 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732114579.221873 2024-11-20 14:56:19,221 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732114579.221873 2024-11-20 14:56:19,221 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732114579.221873 2024-11-20 14:56:19,222 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-relwithdebinfo: 0.00041294097900390625 2024-11-20 14:56:19,222 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-20 14:56:19,222 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732114579.2221355 2024-11-20 14:56:19,222 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732114579.2221355 2024-11-20 14:56:19,222 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732114579.2221355 2024-11-20 14:56:19,222 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732114579.2222695 2024-11-20 14:56:19,222 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732114579.2222695 2024-11-20 14:56:19,222 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732114579.2222695 2024-11-20 14:56:19,222 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-relwithdebinfo: 0.00039076805114746094 2024-11-20 14:56:19,222 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-20 14:56:19,222 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732114579.2225044 2024-11-20 14:56:19,222 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732114579.2225044 2024-11-20 14:56:19,222 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732114579.2225044 2024-11-20 14:56:19,222 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-20 14:56:19,223 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732114579.223199 2024-11-20 14:56:19,223 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732114579.223199 2024-11-20 14:56:19,223 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732114579.223199 2024-11-20 14:56:19,223 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732114579.2236702 2024-11-20 14:56:19,223 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732114579.2236702 2024-11-20 14:56:19,223 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732114579.2236702 2024-11-20 14:56:19,223 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-20 14:56:19,239 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 14:56:19,365 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 14:56:19,365 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-20 14:56:19,365 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732114579.3656135 2024-11-20 14:56:19,365 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732114579.3656135 2024-11-20 14:56:19,365 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732114579.3656135 2024-11-20 14:56:19,365 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732114579.3658166 2024-11-20 14:56:19,365 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732114579.3658166 2024-11-20 14:56:19,365 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732114579.3658166 2024-11-20 14:56:19,365 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 0x7f23cae63880>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/makefiles.dart'} 2024-11-20 14:56:19,366 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-20 14:56:19,366 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-20 14:56:19,366 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 14:56:19,366 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-20 14:56:19,366 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/1732114578.2283564.kf4ijaka/yatmpwetu28vz --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/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/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 14:56:19,741 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/1732114578.2283564.kf4ijaka/yatmpwetu28vz --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/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/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 14:56:19,742 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 14:56:19,742 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732114579.366230487823486,"finish":1732114579.366870641708374,"duration":0.000640153884888},"execution":{"start":1732114579.366871118545532,"finish":1732114579.742135524749756,"duration":0.375264406204224},"postprocessing":{"start":1732114579.742137670516968,"finish":1732114579.742513418197632,"duration":0.000375747680664}},"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/1732114578.2283564.kf4ijaka/yatmpwetu28vz","--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/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/gen_graph.hx3nldvc/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.375264406204224} 2024-11-20 14:56:19,742 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732114579.7429335 2024-11-20 14:56:19,743 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732114579.7429335 2024-11-20 14:56:19,743 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732114579.7429335 2024-11-20 14:56:19,743 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732114579.7431993 2024-11-20 14:56:19,743 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732114579.7431993 2024-11-20 14:56:19,743 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732114579.7431993 2024-11-20 14:56:19,743 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732114579.743401 2024-11-20 14:56:19,743 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732114579.743401 2024-11-20 14:56:19,743 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732114579.743401 2024-11-20 14:56:19,743 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732114579.7436802 2024-11-20 14:56:19,743 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732114579.7436802 2024-11-20 14:56:19,743 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732114579.7436802 2024-11-20 14:56:19,744 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732114579.7443373 2024-11-20 14:56:19,744 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732114579.7443373 2024-11-20 14:56:19,744 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732114579.7443373 2024-11-20 14:56:19,746 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732114579.746278 2024-11-20 14:56:19,746 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732114579.746278 2024-11-20 14:56:19,746 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732114579.746278 2024-11-20 14:56:19,746 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732114579.7465382 2024-11-20 14:56:19,746 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732114579.7465382 2024-11-20 14:56:19,746 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732114579.7465382 2024-11-20 14:56:19,746 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732114579.746704 2024-11-20 14:56:19,746 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732114579.746704 2024-11-20 14:56:19,746 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732114579.746704 2024-11-20 14:56:19,747 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732114579.7474124 2024-11-20 14:56:19,747 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732114579.7474124 2024-11-20 14:56:19,747 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732114579.7474124 2024-11-20 14:56:19,747 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-20 14:56:19,747 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f23caf19ee0>' on 0 suites 2024-11-20 14:56:19,747 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-20 14:56:19,747 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-20 14:56:19,747 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-20 14:56:19,748 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-20 14:56:19,748 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-20 14:56:19,748 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-20 14:56:19,748 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-20 14:56:19,748 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-relwithdebinfo: 0.0010466575622558594 2024-11-20 14:56:19,748 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-relwithdebinfo 2024-11-20 14:56:19,748 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732114579.74881 2024-11-20 14:56:19,748 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732114579.74881 2024-11-20 14:56:19,748 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732114579.74881 2024-11-20 14:56:19,748 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732114579.748964 2024-11-20 14:56:19,749 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732114579.748964 2024-11-20 14:56:19,749 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732114579.748964 2024-11-20 14:56:19,749 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732114579.7490892 2024-11-20 14:56:19,749 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732114579.7490892 2024-11-20 14:56:19,749 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732114579.7490892 2024-11-20 14:56:19,749 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-20 14:56:19,749 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 14:56:19,749 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-20 14:56:19,749 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-20 14:56:19,750 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732114579.750019 2024-11-20 14:56:19,750 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732114579.750019 2024-11-20 14:56:19,750 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732114579.750019 2024-11-20 14:56:19,750 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732114579.7501862 2024-11-20 14:56:19,750 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732114579.7501862 2024-11-20 14:56:19,750 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732114579.7501862 2024-11-20 14:56:19,750 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732114579.7503395 2024-11-20 14:56:19,750 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732114579.7503395 2024-11-20 14:56:19,750 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732114579.7503395 2024-11-20 14:56:19,750 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.168093204498291 2024-11-20 14:56:19,750 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732114579.7505245 2024-11-20 14:56:19,750 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732114579.7505245 2024-11-20 14:56:19,750 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732114579.7505245 2024-11-20 14:56:19,750 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 14:56:19,750 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00023984909057617188 2024-11-20 14:56:19,750 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732114579.7507546 2024-11-20 14:56:19,750 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732114579.7507546 2024-11-20 14:56:19,750 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732114579.7507546 2024-11-20 14:56:19,750 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.00019860267639160156 2024-11-20 14:56:19,751 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 14:56:19,751 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 14:56:19,751 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732114579.7512023 2024-11-20 14:56:19,751 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732114579.7512023 2024-11-20 14:56:19,751 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732114579.7512023 2024-11-20 14:56:19,751 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-20 14:56:19,751 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.821487426757812e-06 2024-11-20 14:56:19,751 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732114579.7514315 2024-11-20 14:56:19,751 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732114579.7514315 2024-11-20 14:56:19,751 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732114579.7514315 2024-11-20 14:56:19,751 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006575584411621094 2024-11-20 14:56:19,751 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732114579.7516148 2024-11-20 14:56:19,751 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732114579.7516148 2024-11-20 14:56:19,751 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732114579.7516148 2024-11-20 14:56:19,751 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732114579.7517266 2024-11-20 14:56:19,751 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732114579.7517266 2024-11-20 14:56:19,751 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732114579.7517266 2024-11-20 14:56:19,751 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732114579.7518637 2024-11-20 14:56:19,751 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732114579.7518637 2024-11-20 14:56:19,751 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732114579.7518637 2024-11-20 14:56:19,752 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 14:56:19,752 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 14:56:19,752 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 14:56:19,752 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732114579.752146 2024-11-20 14:56:19,752 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732114579.752146 2024-11-20 14:56:19,752 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732114579.752146 2024-11-20 14:56:19,752 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732114579.7522779 2024-11-20 14:56:19,752 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732114579.7522779 2024-11-20 14:56:19,752 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732114579.7522779 2024-11-20 14:56:19,752 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732114579.7525566 2024-11-20 14:56:19,752 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732114579.7525566 2024-11-20 14:56:19,752 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732114579.7525566 2024-11-20 14:56:19,752 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732114579.752692 2024-11-20 14:56:19,752 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732114579.752692 2024-11-20 14:56:19,752 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732114579.752692 2024-11-20 14:56:19,752 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0012586116790771484 2024-11-20 14:56:19,752 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732114579.7528708 2024-11-20 14:56:19,752 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732114579.7528708 2024-11-20 14:56:19,752 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732114579.7528708 2024-11-20 14:56:19,753 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732114579.7530825 2024-11-20 14:56:19,753 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732114579.7530825 2024-11-20 14:56:19,753 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732114579.7530825 2024-11-20 14:56:19,753 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732114579.7533662 2024-11-20 14:56:19,753 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732114579.7533662 2024-11-20 14:56:19,753 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732114579.7533662 2024-11-20 14:56:19,753 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732114579.7535172 2024-11-20 14:56:19,753 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732114579.7535172 2024-11-20 14:56:19,753 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732114579.7535172 2024-11-20 14:56:19,753 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732114579.753699 2024-11-20 14:56:19,753 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732114579.753699 2024-11-20 14:56:19,753 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732114579.753699 2024-11-20 14:56:19,753 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732114579.7538345 2024-11-20 14:56:19,753 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732114579.7538345 2024-11-20 14:56:19,753 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732114579.7538345 2024-11-20 14:56:19,754 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.20412278175354 2024-11-20 14:56:19,755 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732114579.7554684 2024-11-20 14:56:19,755 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732114579.7554684 2024-11-20 14:56:19,755 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732114579.7554684 2024-11-20 14:56:19,755 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-20 14:56:19,755 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-20 14:56:19,755 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-20 14:56:19,755 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732114579.7557797 2024-11-20 14:56:19,755 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732114579.7557797 2024-11-20 14:56:19,755 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732114579.7557797 2024-11-20 14:56:19,755 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732114579.7559261 2024-11-20 14:56:19,755 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732114579.7559261 2024-11-20 14:56:19,756 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732114579.7559261 2024-11-20 14:56:19,756 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-20 14:56:19,756 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732114579.7561536 2024-11-20 14:56:19,756 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732114579.7561536 2024-11-20 14:56:19,756 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732114579.7561536 2024-11-20 14:56:19,756 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-20 14:56:19,758 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-20 14:56:19,759 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-20 14:56:19,760 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-20 14:56:19,761 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-20 14:56:19,762 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-20 14:56:19,763 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-20 14:56:19,764 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-20 14:56:19,765 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-20 14:56:19,767 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-20 14:56:19,768 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-20 14:56:19,769 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-20 14:56:19,771 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-20 14:56:19,772 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-20 14:56:19,775 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/36334c63594e55347064715f2d645f726769335f637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,775 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/4562566c4242416f474867375379546e78533032715100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,775 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 63LcYNU4pdq_-d_rgi3_cw => False 2024-11-20 14:56:19,776 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing EbVlBBAoGHg7SyTnxS02qQ => False 2024-11-20 14:56:19,776 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:19,776 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/445278564e656e7a627462446f5f4b465747334f755100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,777 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 14:56:19,777 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing DRxVNenzbtbDo_KFWG3OuQ => False 2024-11-20 14:56:19,778 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:19,779 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/395f78716a51574a544830432d727a5061582d4c6c4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,779 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/42375864345a51423443576c4548462d5f6d414d536700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,779 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 14:56:19,779 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:19,780 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing 9_xqjQWJTH0C-rzPaX-LlA => False 2024-11-20 14:56:19,780 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing B7Xd4ZQB4CWlEHF-_mAMSg => False 2024-11-20 14:56:19,781 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 14:56:19,782 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/664f31643167704e6f543670325179712d4b4b69637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,782 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing fO1d1gpNoT6p2Qyq-KKicw => False 2024-11-20 14:56:19,783 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/634434766f7a4b6d6d6865457342316164526445697700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,783 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing cD4vozKmmheEsB1adRdEiw => False 2024-11-20 14:56:19,785 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/44637474577a5a56646154686e4d44776e727a39787700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,785 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/6c4947777231515257547871326c506e643077646f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,786 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing DcttWzZVdaThnMDwnrz9xw => False 2024-11-20 14:56:19,786 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing lIGwr1QRWTxq2lPnd0wdoA => False 2024-11-20 14:56:19,786 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/70787a7265375f4170533537477a50673836524a4b6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:19,787 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing pxzre7_ApS57GzPg86RJKg => False 2024-11-20 14:56:19,787 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-20 14:56:19,787 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 14:56:19,791 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732114579.7916017 2024-11-20 14:56:19,791 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732114579.7916017 2024-11-20 14:56:19,791 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732114579.7916017 2024-11-20 14:56:19,791 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.03770637512207031 2024-11-20 14:56:19,792 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732114579.7920687 2024-11-20 14:56:19,792 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732114579.7920687 2024-11-20 14:56:19,792 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732114579.7920687 2024-11-20 14:56:19,792 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732114579.792257 2024-11-20 14:56:19,792 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732114579.792257 2024-11-20 14:56:19,792 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732114579.792257 2024-11-20 14:56:19,792 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 14:56:19,792 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 14:56:19,792 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 14:56:19,792 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 14:56:19,792 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 14:56:19,792 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 14:56:19,792 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 14:56:19,793 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 14:56:19,793 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 14:56:19,793 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 14:56:19,794 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 14:56:19,794 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 14:56:19,794 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 14:56:19,794 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 14:56:19,794 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 14:56:19,794 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 14:56:19,794 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 14:56:19,794 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-20 14:56:19,794 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-20 14:56:19,794 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732114579.794397 2024-11-20 14:56:19,794 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732114579.794397 2024-11-20 14:56:19,794 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732114579.794397 2024-11-20 14:56:19,794 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-20 14:56:19,806 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732114578.2283564.kf4ijaka/2283564_g0h2jys', '0'] 2024-11-20 14:56:19,806 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-20 14:56:19,830 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/8jat/STAMP 2024-11-20 14:56:19,830 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.743171691894531e-05 2024-11-20 14:56:19,830 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00014734268188476562 2024-11-20 14:56:19,830 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 6.151199340820312e-05 2024-11-20 14:56:19,830 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 5.841255187988281e-05 2024-11-20 14:56:19,831 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-20 14:56:19,988 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-20 14:56:19,992 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid B7Xd4ZQB4CWlEHF-_mAMSg) address: (2283150, 316934, 'unix:/home/runner/.ya/build/cache/7/psingb7UgBa.tmp') 2024-11-20 14:56:20,004 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/44637474577a5a56646154686e4d44776e727a39787700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,004 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/42375864345a51423443576c4548462d5f6d414d536700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,004 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/4562566c4242416f474867375379546e78533032715100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,006 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing DcttWzZVdaThnMDwnrz9xw => False 2024-11-20 14:56:20,007 DEBUG (urllib3.connectionpool) [Worker-009] http://cachesrv.internal:8081 "HEAD /ac/445278564e656e7a627462446f5f4b465747334f755100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,007 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 63LcYNU4pdq_-d_rgi3_cw created (provider=None) 2024-11-20 14:56:20,008 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing B7Xd4ZQB4CWlEHF-_mAMSg => False 2024-11-20 14:56:20,008 DEBUG (urllib3.connectionpool) [Worker-010] http://cachesrv.internal:8081 "HEAD /ac/634434766f7a4b6d6d6865457342316164526445697700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,008 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/36334c63594e55347064715f2d645f726769335f637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,009 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing EbVlBBAoGHg7SyTnxS02qQ => False 2024-11-20 14:56:20,013 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-007] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-20 14:56:20,013 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/664f31643167704e6f543670325179712d4b4b69637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,013 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/70787a7265375f4170533537477a50673836524a4b6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,013 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/6c4947777231515257547871326c506e643077646f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,013 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "HEAD /ac/395f78716a51574a544830432d727a5061582d4c6c4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,014 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-009] Bazel-remote Probing DRxVNenzbtbDo_KFWG3OuQ => False 2024-11-20 14:56:20,014 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for EbVlBBAoGHg7SyTnxS02qQ created (provider=None) 2024-11-20 14:56:20,015 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-010] Bazel-remote Probing cD4vozKmmheEsB1adRdEiw => False 2024-11-20 14:56:20,015 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-024] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-20 14:56:20,016 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing 63LcYNU4pdq_-d_rgi3_cw => False 2024-11-20 14:56:20,018 DEBUG (yalibrary.toolscache) [Worker-007] Tools cache attempt: (request for resource 7336031045) 2024-11-20 14:56:20,018 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing fO1d1gpNoT6p2Qyq-KKicw => False 2024-11-20 14:56:20,019 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing pxzre7_ApS57GzPg86RJKg => False 2024-11-20 14:56:20,020 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing lIGwr1QRWTxq2lPnd0wdoA => False 2024-11-20 14:56:20,020 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-016] Bazel-remote Probing 9_xqjQWJTH0C-rzPaX-LlA => False 2024-11-20 14:56:20,022 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for DRxVNenzbtbDo_KFWG3OuQ created (provider=None) 2024-11-20 14:56:20,023 DEBUG (yalibrary.toolscache) [Worker-024] Tools cache attempt: (request for resource 7458630270) 2024-11-20 14:56:20,024 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:20,025 DEBUG (yalibrary.toolscache) [Worker-007] tc cache stats: TotalKnownSize: 62124032 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 14:56:20,026 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 9_xqjQWJTH0C-rzPaX-LlA created (provider=None) 2024-11-20 14:56:20,026 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:20,027 DEBUG (yalibrary.toolscache) [Worker-024] tc cache stats: TotalKnownSize: 62124032 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 14:56:20,028 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 14:56:20,028 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for B7Xd4ZQB4CWlEHF-_mAMSg created (provider=None) 2024-11-20 14:56:20,028 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 14:56:20,028 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 14:56:20,029 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for DcttWzZVdaThnMDwnrz9xw created (provider=None) 2024-11-20 14:56:20,029 DEBUG (yalibrary.fetcher.cache_helper) [Worker-024] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 14:56:20,030 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for cD4vozKmmheEsB1adRdEiw created (provider=None) 2024-11-20 14:56:20,030 DEBUG (library.python.filelock) [Worker-007] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 14:56:20,030 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for fO1d1gpNoT6p2Qyq-KKicw created (provider=None) 2024-11-20 14:56:20,031 DEBUG (library.python.filelock) [Worker-024] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 14:56:20,031 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 14:56:20,031 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for lIGwr1QRWTxq2lPnd0wdoA created (provider=None) 2024-11-20 14:56:20,031 DEBUG (yalibrary.fetcher.cache_helper) [Worker-024] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 14:56:20,031 DEBUG (exts.http_client) [Worker-007] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.w62xsirb.stm22gng.part, expect md5- 2024-11-20 14:56:20,031 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for pxzre7_ApS57GzPg86RJKg created (provider=None) 2024-11-20 14:56:20,032 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-20 14:56:20,033 DEBUG (exts.http_client) [Worker-024] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.l1qmagb9.pukoj9c0.part, expect md5- 2024-11-20 14:56:20,040 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-20 14:56:20,040 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-20 14:56:20,040 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:20,046 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-20 14:56:20,048 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-016] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 14:56:20,055 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-20 14:56:20,056 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-20 14:56:20,056 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-20 14:56:20,063 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-20 14:56:20,064 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-20 14:56:20,065 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-20 14:56:20,072 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-20 14:56:20,073 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-20 14:56:20,074 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-20 14:56:20,074 DEBUG (yalibrary.toolscache) [Worker-005] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-20 14:56:20,076 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-20 14:56:20,083 DEBUG (urllib3.connectionpool) [Worker-016] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-20 14:56:20,084 DEBUG (yalibrary.toolscache) [Worker-016] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-20 14:56:20,170 DEBUG (exts.http_client) [Worker-007] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Wed, 20 Nov 2024 14:56:20 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: 7de35e2f4000bbac NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 14:56:20,278 DEBUG (exts.http_client) [Worker-024] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Wed, 20 Nov 2024 14:56:20 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: 311fa50a0c9baca5 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 14:56:20,448 DEBUG (exts.http_client) [Worker-007] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.w62xsirb, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.416638 2024-11-20 14:56:20,448 DEBUG (yalibrary.fetcher.common) [Worker-007] extract /home/runner/.ya/tools/v4/7336031045/resource.w62xsirb to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-20 14:56:20,603 DEBUG (library.python.filelock) [Worker-007] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 14:56:20,606 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (get uid 02efcea4472d7e663e2ac4a269e01c22) 2024-11-20 14:56:20,609 DEBUG (yalibrary.toolscache) [Worker-064] AC cache attempt: (get uid dac10e5a0e191fad07ad11f370dc5d78) 2024-11-20 14:56:20,617 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/6e2a765e829db52312fb926517a97a5bf89b420aa4e74a738255cc523b988f11 HTTP/1.1" 404 0 2024-11-20 14:56:20,617 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing 02efcea4472d7e663e2ac4a269e01c22 => False 2024-11-20 14:56:20,617 DEBUG (root) [Worker-007] Run node DRxVNenzbtbDo_KFWG3OuQ in build root /home/runner/.ya/build/build_root/8jat/000012 2024-11-20 14:56:20,618 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "HEAD /ac/2af27aa9d68205e5320a2b41ea0ba15caa722ffa358bf4d3beebb1eaf7689511 HTTP/1.1" 404 0 2024-11-20 14:56:20,619 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-064] Bazel-remote Probing dac10e5a0e191fad07ad11f370dc5d78 => False 2024-11-20 14:56:20,619 DEBUG (root) [Worker-064] Run node DcttWzZVdaThnMDwnrz9xw in build root /home/runner/.ya/build/build_root/8jat/00000d 2024-11-20 14:56:20,974 DEBUG (yalibrary.toolscache) [Worker-069] AC cache attempt: (put uid DRxVNenzbtbDo_KFWG3OuQ) 2024-11-20 14:56:20,975 DEBUG (yalibrary.runner.tasks.result) [Worker-007] Result node DRxVNenzbtbDo_KFWG3OuQ processed successfully: True 2024-11-20 14:56:20,976 DEBUG (yalibrary.toolscache) [Worker-069] AC cache attempt: (put uid 02efcea4472d7e663e2ac4a269e01c22) 2024-11-20 14:56:20,981 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "HEAD /ac/445278564e656e7a627462446f5f4b465747334f755100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:20,981 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Bazel-remote Probing DRxVNenzbtbDo_KFWG3OuQ => False 2024-11-20 14:56:20,981 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Put ydb/docs/ru/core/docs-ru-core.docslib(DRxVNenzbtbDo_KFWG3OuQ) to Bazel-remote 2024-11-20 14:56:21,001 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/648e13dfdff07a96c5ac45b1dbe60b4b758fb7ed51158d45e79e1a67cb8150e9 HTTP/1.1" 200 0 2024-11-20 14:56:21,010 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/927c46f51db3c744a4c2381fce85d03f010e157e7c6e8c6c96f2b1929776355d HTTP/1.1" 200 0 2024-11-20 14:56:21,201 DEBUG (yalibrary.runner.tasks.result) [Worker-064] Result node DcttWzZVdaThnMDwnrz9xw processed successfully: True 2024-11-20 14:56:21,202 DEBUG (yalibrary.toolscache) [Worker-083] AC cache attempt: (put uid DcttWzZVdaThnMDwnrz9xw) 2024-11-20 14:56:21,204 DEBUG (yalibrary.toolscache) [Worker-083] AC cache attempt: (put uid dac10e5a0e191fad07ad11f370dc5d78) 2024-11-20 14:56:21,209 DEBUG (urllib3.connectionpool) [Worker-081] http://cachesrv.internal:8081 "HEAD /ac/44637474577a5a56646154686e4d44776e727a39787700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:21,209 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-081] Bazel-remote Probing DcttWzZVdaThnMDwnrz9xw => False 2024-11-20 14:56:21,209 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-081] Put ydb/docs/__docs_internal.docslib.fake(DcttWzZVdaThnMDwnrz9xw) to Bazel-remote 2024-11-20 14:56:21,219 DEBUG (urllib3.connectionpool) [Worker-081] http://cachesrv.internal:8081 "PUT /cas/b7eeb8019e1032a8f1d9ffc50189b7b40e43d1b385e3afeb51e79fbb1424e339 HTTP/1.1" 200 0 2024-11-20 14:56:21,229 DEBUG (urllib3.connectionpool) [Worker-081] http://cachesrv.internal:8081 "PUT /cas/866971f64455c3824674a0057c9398b3d60db626c4cc5cd667955149852d2f66 HTTP/1.1" 200 0 2024-11-20 14:56:21,365 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/79c9eb3266d0b4d86dd78a85a3f6d3fc461f5b0f90ecadb43309405ee381e579 HTTP/1.1" 200 0 2024-11-20 14:56:21,385 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /ac/445278564e656e7a627462446f5f4b465747334f755100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:21,385 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Put ydb/docs/ru/core/docs-ru-core.docslib(DRxVNenzbtbDo_KFWG3OuQ) size=29634833 to Bazel-remote completed 2024-11-20 14:56:21,791 DEBUG (urllib3.connectionpool) [Worker-081] http://cachesrv.internal:8081 "PUT /cas/0ea28a441599949540f762d554ba623ed5da564f957dcc0fe0480936528dfc87 HTTP/1.1" 200 0 2024-11-20 14:56:21,800 DEBUG (urllib3.connectionpool) [Worker-081] http://cachesrv.internal:8081 "PUT /ac/44637474577a5a56646154686e4d44776e727a39787700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:21,800 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-081] Put ydb/docs/__docs_internal.docslib.fake(DcttWzZVdaThnMDwnrz9xw) size=51589393 to Bazel-remote completed 2024-11-20 14:56:29,175 DEBUG (exts.http_client) [Worker-024] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.l1qmagb9, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=9.142079 2024-11-20 14:56:29,175 DEBUG (yalibrary.fetcher.common) [Worker-024] extract /home/runner/.ya/tools/v4/7458630270/resource.l1qmagb9 to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-20 14:56:29,554 DEBUG (library.python.filelock) [Worker-024] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 14:56:29,555 DEBUG (yalibrary.toolscache) [Worker-024] AC cache attempt: (get uid 8391cf66e2f1711d9ed7ff885b73bfb9) 2024-11-20 14:56:29,556 DEBUG (yalibrary.toolscache) [Worker-096] AC cache attempt: (get uid 15df8241e8560cdfccd4fd116499af1e) 2024-11-20 14:56:29,556 DEBUG (yalibrary.toolscache) [Worker-094] AC cache attempt: (get uid 9f6e3e987761e0c617669f6210ed14af) 2024-11-20 14:56:29,556 DEBUG (yalibrary.toolscache) [Worker-095] AC cache attempt: (get uid 790565f822f8d1b89675396d49243cd9) 2024-11-20 14:56:29,564 DEBUG (urllib3.connectionpool) [Worker-024] http://cachesrv.internal:8081 "HEAD /ac/865bfa22f6f81a358516bb7bde4140c6624dd040161fb86737eadfd9bc4ff711 HTTP/1.1" 404 0 2024-11-20 14:56:29,564 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-024] Bazel-remote Probing 8391cf66e2f1711d9ed7ff885b73bfb9 => False 2024-11-20 14:56:29,564 DEBUG (urllib3.connectionpool) [Worker-096] http://cachesrv.internal:8081 "HEAD /ac/aae133f8c060b84236aafe52912d5da29bca5119a6c11abe3622c6fdd83e4b11 HTTP/1.1" 404 0 2024-11-20 14:56:29,565 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-096] Bazel-remote Probing 15df8241e8560cdfccd4fd116499af1e => False 2024-11-20 14:56:29,566 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "HEAD /ac/1c43d39c7004e9d4e1ae4fa3d42ab0b25f1756d3b531070c4513400d9149b911 HTTP/1.1" 404 0 2024-11-20 14:56:29,566 DEBUG (root) [Worker-024] Run node B7Xd4ZQB4CWlEHF-_mAMSg in build root /home/runner/.ya/build/build_root/8jat/00000e 2024-11-20 14:56:29,566 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Bazel-remote Probing 9f6e3e987761e0c617669f6210ed14af => False 2024-11-20 14:56:29,566 DEBUG (root) [Worker-096] Run node EbVlBBAoGHg7SyTnxS02qQ in build root /home/runner/.ya/build/build_root/8jat/000010 2024-11-20 14:56:29,567 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "HEAD /ac/c599e330716f95f50e202278c174d9ab85d1ace04593c6e2e19039997a099b11 HTTP/1.1" 404 0 2024-11-20 14:56:29,568 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-095] Bazel-remote Probing 790565f822f8d1b89675396d49243cd9 => False 2024-11-20 14:56:29,601 DEBUG (root) [Worker-094] Run node 9_xqjQWJTH0C-rzPaX-LlA in build root /home/runner/.ya/build/build_root/8jat/000018 2024-11-20 14:56:29,622 DEBUG (root) [Worker-095] Run node fO1d1gpNoT6p2Qyq-KKicw in build root /home/runner/.ya/build/build_root/8jat/000015 2024-11-20 14:56:33,366 DEBUG (yalibrary.toolscache) [Worker-100] AC cache attempt: (put uid B7Xd4ZQB4CWlEHF-_mAMSg) 2024-11-20 14:56:33,367 DEBUG (yalibrary.toolscache) [Worker-103] AC cache attempt: (get uid 4466a27fc1dd7b4e0eadf456ecbb1c0b) 2024-11-20 14:56:33,368 DEBUG (yalibrary.runner.tasks.result) [Worker-024] Result node B7Xd4ZQB4CWlEHF-_mAMSg processed successfully: True 2024-11-20 14:56:33,374 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "HEAD /ac/42375864345a51423443576c4548462d5f6d414d536700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:33,375 DEBUG (yalibrary.toolscache) [Worker-100] AC cache attempt: (put uid 8391cf66e2f1711d9ed7ff885b73bfb9) 2024-11-20 14:56:33,375 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Bazel-remote Probing B7Xd4ZQB4CWlEHF-_mAMSg => False 2024-11-20 14:56:33,376 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(B7Xd4ZQB4CWlEHF-_mAMSg) to Bazel-remote 2024-11-20 14:56:33,376 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "HEAD /ac/1224ccf48a6b5fafbdba61f90a12db1904647a5b39b8a3082fbcdd6640c68711 HTTP/1.1" 404 0 2024-11-20 14:56:33,377 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Bazel-remote Probing 4466a27fc1dd7b4e0eadf456ecbb1c0b => False 2024-11-20 14:56:33,381 DEBUG (yalibrary.toolscache) [Worker-119] AC cache attempt: (put uid EbVlBBAoGHg7SyTnxS02qQ) 2024-11-20 14:56:33,382 DEBUG (yalibrary.toolscache) [Worker-114] AC cache attempt: (get uid 131b5edfde57d1ead1e6565476c54d2e) 2024-11-20 14:56:33,382 DEBUG (root) [Worker-103] Run node pxzre7_ApS57GzPg86RJKg in build root /home/runner/.ya/build/build_root/8jat/000016 2024-11-20 14:56:33,382 DEBUG (yalibrary.toolscache) [Worker-119] AC cache attempt: (put uid 15df8241e8560cdfccd4fd116499af1e) 2024-11-20 14:56:33,384 DEBUG (yalibrary.runner.tasks.result) [Worker-116] Result node EbVlBBAoGHg7SyTnxS02qQ processed successfully: True 2024-11-20 14:56:33,386 DEBUG (urllib3.connectionpool) [Worker-115] http://cachesrv.internal:8081 "HEAD /ac/4562566c4242416f474867375379546e78533032715100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:33,387 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-115] Bazel-remote Probing EbVlBBAoGHg7SyTnxS02qQ => False 2024-11-20 14:56:33,387 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-115] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(EbVlBBAoGHg7SyTnxS02qQ) to Bazel-remote 2024-11-20 14:56:33,388 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/c64dd9fbe3027f0fdc70e1a9f97a014db6a316ff425aa6ea1d780c25bd1875f9 HTTP/1.1" 200 0 2024-11-20 14:56:33,390 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "HEAD /ac/9a7f66453e631a73a7dc83c11118643ac3cb18fcd6b0e71603b73af0945f8711 HTTP/1.1" 404 0 2024-11-20 14:56:33,390 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-114] Bazel-remote Probing 131b5edfde57d1ead1e6565476c54d2e => False 2024-11-20 14:56:33,391 DEBUG (root) [Worker-114] Run node 63LcYNU4pdq_-d_rgi3_cw in build root /home/runner/.ya/build/build_root/8jat/000014 2024-11-20 14:56:33,398 DEBUG (yalibrary.toolscache) [Worker-130] AC cache attempt: (put uid 9_xqjQWJTH0C-rzPaX-LlA) 2024-11-20 14:56:33,399 DEBUG (urllib3.connectionpool) [Worker-115] http://cachesrv.internal:8081 "PUT /cas/27e346fd3b9ce9ef1662a5f0733aa5c9a3691638d4cd7a73ecf24aa6fd8cdc0f HTTP/1.1" 200 0 2024-11-20 14:56:33,399 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/c28b50bee15b46741b6f710cc278180ff52f5ec09be282d4da79068740704a91 HTTP/1.1" 200 0 2024-11-20 14:56:33,399 DEBUG (yalibrary.toolscache) [Worker-132] AC cache attempt: (get uid 28f2396f8ba292af91d02726c62c6b6b) 2024-11-20 14:56:33,400 DEBUG (yalibrary.toolscache) [Worker-130] AC cache attempt: (put uid 9f6e3e987761e0c617669f6210ed14af) 2024-11-20 14:56:33,404 DEBUG (yalibrary.runner.tasks.result) [Worker-128] Result node 9_xqjQWJTH0C-rzPaX-LlA processed successfully: True 2024-11-20 14:56:33,405 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "HEAD /ac/395f78716a51574a544830432d727a5061582d4c6c4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:33,405 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Bazel-remote Probing 9_xqjQWJTH0C-rzPaX-LlA => False 2024-11-20 14:56:33,406 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(9_xqjQWJTH0C-rzPaX-LlA) to Bazel-remote 2024-11-20 14:56:33,409 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "HEAD /ac/bc38aa3cccf196f85546d3ba6867af6de2ee3775d5ca9da8fbaac5783dfec611 HTTP/1.1" 404 0 2024-11-20 14:56:33,409 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Bazel-remote Probing 28f2396f8ba292af91d02726c62c6b6b => False 2024-11-20 14:56:33,409 DEBUG (root) [Worker-132] Run node cD4vozKmmheEsB1adRdEiw in build root /home/runner/.ya/build/build_root/8jat/000013 2024-11-20 14:56:33,411 DEBUG (urllib3.connectionpool) [Worker-115] http://cachesrv.internal:8081 "PUT /cas/607a1d1bf8d9c669df40e2f17504a5ebf08a88135b3f05cd87af7ea1c70e0b4e HTTP/1.1" 200 0 2024-11-20 14:56:33,415 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/49fdbab7214c5d5aadacc644e6d5ee4d4deeb933a1ba9145eb74a0bc9df121ee HTTP/1.1" 200 0 2024-11-20 14:56:33,425 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/576a7440bae548fa4ca6343c5362049650ddb108b03ac62cf18f0236c47b2fb3 HTTP/1.1" 200 0 2024-11-20 14:56:33,462 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/0b3e4cca7618367f9cc86c909ea8b1a552338f1a1878ea7b7d9578d1964c6caa HTTP/1.1" 200 0 2024-11-20 14:56:33,471 DEBUG (urllib3.connectionpool) [Worker-115] http://cachesrv.internal:8081 "PUT /cas/7563a72f1e6f4656f17f16ab9ab66c7832e93a2350f81be7b6a60ba2c14219b2 HTTP/1.1" 200 0 2024-11-20 14:56:33,474 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /ac/42375864345a51423443576c4548462d5f6d414d536700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:33,474 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(B7Xd4ZQB4CWlEHF-_mAMSg) size=1092571 to Bazel-remote completed 2024-11-20 14:56:33,480 DEBUG (urllib3.connectionpool) [Worker-115] http://cachesrv.internal:8081 "PUT /ac/4562566c4242416f474867375379546e78533032715100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:33,480 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-115] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(EbVlBBAoGHg7SyTnxS02qQ) size=1092603 to Bazel-remote completed 2024-11-20 14:56:33,481 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/32a1f00a10010b42a22c3d1bda6523dccb2886bd1c794df1e895dae155fca0ed HTTP/1.1" 200 0 2024-11-20 14:56:33,491 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /ac/395f78716a51574a544830432d727a5061582d4c6c4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:33,491 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(9_xqjQWJTH0C-rzPaX-LlA) size=1092599 to Bazel-remote completed 2024-11-20 14:56:38,765 DEBUG (yalibrary.toolscache) [Worker-017] AC cache attempt: (put uid cD4vozKmmheEsB1adRdEiw) 2024-11-20 14:56:38,766 DEBUG (yalibrary.runner.tasks.result) [Worker-006] Result node cD4vozKmmheEsB1adRdEiw processed successfully: True 2024-11-20 14:56:38,767 DEBUG (yalibrary.toolscache) [Worker-017] AC cache attempt: (put uid 28f2396f8ba292af91d02726c62c6b6b) 2024-11-20 14:56:38,771 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "HEAD /ac/634434766f7a4b6d6d6865457342316164526445697700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:38,771 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Bazel-remote Probing cD4vozKmmheEsB1adRdEiw => False 2024-11-20 14:56:38,772 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(cD4vozKmmheEsB1adRdEiw) to Bazel-remote 2024-11-20 14:56:38,782 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/0346bc933d6588e528f51cc84eb28a3392425075f327601fe00fe0a48bfe67f5 HTTP/1.1" 200 0 2024-11-20 14:56:38,792 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/074276320ece007ccfe63566d85bbeda2fea61fdbb5b176f44571265440460fd HTTP/1.1" 200 0 2024-11-20 14:56:38,868 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/3f0f9975450dd203ba839ffd1a19f6e388010a2083d1bd732c66d8bc2088001c HTTP/1.1" 200 0 2024-11-20 14:56:38,877 DEBUG (yalibrary.toolscache) [Worker-027] AC cache attempt: (put uid 63LcYNU4pdq_-d_rgi3_cw) 2024-11-20 14:56:38,878 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /ac/634434766f7a4b6d6d6865457342316164526445697700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:38,878 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(cD4vozKmmheEsB1adRdEiw) size=2780741 to Bazel-remote completed 2024-11-20 14:56:38,879 DEBUG (yalibrary.runner.tasks.result) [Worker-026] Result node 63LcYNU4pdq_-d_rgi3_cw processed successfully: True 2024-11-20 14:56:38,879 DEBUG (yalibrary.toolscache) [Worker-027] AC cache attempt: (put uid 131b5edfde57d1ead1e6565476c54d2e) 2024-11-20 14:56:38,883 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "HEAD /ac/36334c63594e55347064715f2d645f726769335f637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:38,884 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-114] Bazel-remote Probing 63LcYNU4pdq_-d_rgi3_cw => False 2024-11-20 14:56:38,884 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-114] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(63LcYNU4pdq_-d_rgi3_cw) to Bazel-remote 2024-11-20 14:56:38,895 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "PUT /cas/315fb0b2ec694ca9503cae14bf611f64e12802c478a2cc5f456d1f2c9fa2e129 HTTP/1.1" 200 0 2024-11-20 14:56:38,904 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "PUT /cas/b6e07adedc60022b045d1f1ff4d364802f6c20dc59ec8666f4c32b9f92a2cb57 HTTP/1.1" 200 0 2024-11-20 14:56:38,973 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid pxzre7_ApS57GzPg86RJKg) 2024-11-20 14:56:38,975 DEBUG (yalibrary.runner.tasks.result) [Worker-037] Result node pxzre7_ApS57GzPg86RJKg processed successfully: True 2024-11-20 14:56:38,975 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid 4466a27fc1dd7b4e0eadf456ecbb1c0b) 2024-11-20 14:56:38,976 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "PUT /cas/bb4c4d95d331dd6f14f641982cf31b0e7db0e756f54fc9eade5ed4b10decefba HTTP/1.1" 200 0 2024-11-20 14:56:38,979 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "HEAD /ac/70787a7265375f4170533537477a50673836524a4b6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:38,980 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Bazel-remote Probing pxzre7_ApS57GzPg86RJKg => False 2024-11-20 14:56:38,980 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(pxzre7_ApS57GzPg86RJKg) to Bazel-remote 2024-11-20 14:56:38,985 DEBUG (urllib3.connectionpool) [Worker-114] http://cachesrv.internal:8081 "PUT /ac/36334c63594e55347064715f2d645f726769335f637700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:38,985 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-114] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(63LcYNU4pdq_-d_rgi3_cw) size=2780769 to Bazel-remote completed 2024-11-20 14:56:38,990 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/14471c0a37b81e6e0bbd217913b64a2509989e505e0716256b963424f88d6376 HTTP/1.1" 200 0 2024-11-20 14:56:38,999 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/55d8f687da808a9030f84c3bc3b97310e6a4bd57ffb280a04b0d586b9e6cf8a5 HTTP/1.1" 200 0 2024-11-20 14:56:39,033 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /cas/be1998d638a405fb34765c8da8ea332748bbd62d258f56eb9c87f501f9016264 HTTP/1.1" 200 0 2024-11-20 14:56:39,042 DEBUG (urllib3.connectionpool) [Worker-103] http://cachesrv.internal:8081 "PUT /ac/70787a7265375f4170533537477a50673836524a4b6700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:39,042 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-103] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(pxzre7_ApS57GzPg86RJKg) size=2780768 to Bazel-remote completed 2024-11-20 14:56:50,183 DEBUG (yalibrary.toolscache) [Worker-048] AC cache attempt: (put uid fO1d1gpNoT6p2Qyq-KKicw) 2024-11-20 14:56:50,183 DEBUG (yalibrary.toolscache) [Worker-049] AC cache attempt: (get uid ab68fa9ab1f273169baa468387572c1c) 2024-11-20 14:56:50,184 DEBUG (yalibrary.runner.tasks.result) [Worker-047] Result node fO1d1gpNoT6p2Qyq-KKicw processed successfully: True 2024-11-20 14:56:50,185 DEBUG (yalibrary.toolscache) [Worker-048] AC cache attempt: (put uid 790565f822f8d1b89675396d49243cd9) 2024-11-20 14:56:50,188 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "HEAD /ac/664f31643167704e6f543670325179712d4b4b69637700000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:56:50,189 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-095] Bazel-remote Probing fO1d1gpNoT6p2Qyq-KKicw => False 2024-11-20 14:56:50,189 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-095] Put ydb/docs/preprocessed.tar.gz(fO1d1gpNoT6p2Qyq-KKicw) to Bazel-remote 2024-11-20 14:56:50,192 DEBUG (urllib3.connectionpool) [Worker-049] http://cachesrv.internal:8081 "HEAD /ac/fa31070e330b37058d336f5aa06230b86acd9050a1b0d3b7ac12fe085f680a11 HTTP/1.1" 404 0 2024-11-20 14:56:50,193 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-049] Bazel-remote Probing ab68fa9ab1f273169baa468387572c1c => False 2024-11-20 14:56:50,193 DEBUG (root) [Worker-049] Run node lIGwr1QRWTxq2lPnd0wdoA in build root /home/runner/.ya/build/build_root/8jat/000017 2024-11-20 14:56:50,199 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "PUT /cas/851a928b683acae257fdbf2946072f663bcd777f290c66ab1ccd864307b1fc33 HTTP/1.1" 200 0 2024-11-20 14:56:50,208 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "PUT /cas/bc0956deefb3ac3fd73b4689a76872077a624ce22e342c2c2629f0beca9f0856 HTTP/1.1" 200 0 2024-11-20 14:56:50,581 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "PUT /cas/96154bfb94b262a9e1ba4b91f18a54aa97fa0e6cb3570f11b93f3da3e8dc29ff HTTP/1.1" 200 0 2024-11-20 14:56:50,591 DEBUG (urllib3.connectionpool) [Worker-095] http://cachesrv.internal:8081 "PUT /ac/664f31643167704e6f543670325179712d4b4b69637700000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:56:50,592 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-095] Put ydb/docs/preprocessed.tar.gz(fO1d1gpNoT6p2Qyq-KKicw) size=36209450 to Bazel-remote completed 2024-11-20 14:57:37,678 DEBUG (yalibrary.toolscache) [Worker-059] AC cache attempt: (put uid lIGwr1QRWTxq2lPnd0wdoA) 2024-11-20 14:57:37,679 DEBUG (yalibrary.runner.tasks.result) [Worker-049] Result node lIGwr1QRWTxq2lPnd0wdoA processed successfully: True 2024-11-20 14:57:37,680 DEBUG (yalibrary.toolscache) [Worker-059] AC cache attempt: (put uid ab68fa9ab1f273169baa468387572c1c) 2024-11-20 14:57:37,685 DEBUG (urllib3.connectionpool) [Worker-058] http://cachesrv.internal:8081 "HEAD /ac/6c4947777231515257547871326c506e643077646f4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 14:57:37,685 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-058] Bazel-remote Probing lIGwr1QRWTxq2lPnd0wdoA => False 2024-11-20 14:57:37,686 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-058] Put ydb/docs/ydb-docs.tar.gz(lIGwr1QRWTxq2lPnd0wdoA) to Bazel-remote 2024-11-20 14:57:37,696 DEBUG (urllib3.connectionpool) [Worker-058] http://cachesrv.internal:8081 "PUT /cas/25525272476330f95985038a0758350d77c12a7ec9e48735d016bf8a8ff59101 HTTP/1.1" 200 0 2024-11-20 14:57:37,706 DEBUG (urllib3.connectionpool) [Worker-058] http://cachesrv.internal:8081 "PUT /cas/413b2c926d1aaf6acf8b50c2b8bd57ffe8f6ed5508c4ee0ec7c251c280b1b61d HTTP/1.1" 200 0 2024-11-20 14:57:38,256 DEBUG (urllib3.connectionpool) [Worker-058] http://cachesrv.internal:8081 "PUT /cas/19714aa415e9b9b4f0779cbf3ab50a9a5af22ac0d5e78aba3f59f057d5916e72 HTTP/1.1" 200 0 2024-11-20 14:57:38,266 DEBUG (urllib3.connectionpool) [Worker-058] http://cachesrv.internal:8081 "PUT /ac/6c4947777231515257547871326c506e643077646f4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 14:57:38,266 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-058] Put ydb/docs/ydb-docs.tar.gz(lIGwr1QRWTxq2lPnd0wdoA) size=39680084 to Bazel-remote completed 2024-11-20 14:57:38,333 DEBUG (yalibrary.runner.build_root) [Worker-072] Build root /home/runner/.ya/build/build_root/8jat created=25 left=12 2024-11-20 14:57:38,333 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/8jat/STAMP 2024-11-20 14:57:38,344 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(B7Xd4ZQB4CWlEHF-_mAMSg$(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 14:57:38,344 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(EbVlBBAoGHg7SyTnxS02qQ$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(9_xqjQWJTH0C-rzPaX-LlA$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(cD4vozKmmheEsB1adRdEiw$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(63LcYNU4pdq_-d_rgi3_cw$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(pxzre7_ApS57GzPg86RJKg$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(fO1d1gpNoT6p2Qyq-KKicw$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(lIGwr1QRWTxq2lPnd0wdoA$(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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-20 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.00484824180603,"qty":1},"prepare_all_nodes":{"sum":0.044226169586182,"qty":1},"compact_cache":{"sum":0.017002582550049,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.400235414505005,"qty":13},"prepare[DO]":{"sum":0.002382755279541,"qty":3},"put_in_cache[DO]":{"sum":0.048802375793457,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.590805768966675,"qty":1},"DO":{"sum":97.026513576507568,"qty":10},"result[DO]":{"sum":0.027277946472168,"qty":10},"write_through_caches[DO]":{"sum":0.081303596496582,"qty":10},"put_in_dist_cache[DO]":{"sum":2.886823177337646,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":9.538674592971802,"qty":1},"clean_build_root":{"sum":0.000271081924438,"qty":1}},"all":{"qty":75,"sum":110.669167280197144},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732114580.015519857406616,1732114589.554194450378418],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":78.356317043304443,"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 14:57:38,345 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-20 14:57:38,345 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732114658.3459466 2024-11-20 14:57:38,346 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732114658.3459466 2024-11-20 14:57:38,346 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732114658.3459466 2024-11-20 14:57:38,346 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732114658.3464031 2024-11-20 14:57:38,346 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732114658.3464031 2024-11-20 14:57:38,346 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732114658.3464031 2024-11-20 14:57:38,363 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-20 14:57:38,363 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-20 14:57:38,363 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,363 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,363 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-20 14:57:38,363 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-20 14:57:38,363 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-20 14:57:38,366 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-20 14:57:38,375 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,375 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,377 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,377 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 14:57:38,377 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732114658.3772662 2024-11-20 14:57:38,377 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732114658.3772662 2024-11-20 14:57:38,377 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732114658.3772662 2024-11-20 14:57:38,377 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-20 14:57:38,378 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39874560 NonComputedCount: 2 2024-11-20 14:57:38,378 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-20 14:57:38,379 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168764531 2024-11-20 14:57:38,379 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732114658.3793077 2024-11-20 14:57:38,379 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732114658.3793077 2024-11-20 14:57:38,379 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732114658.3793077 2024-11-20 14:57:38,379 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-20 14:57:38,380 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-20 14:57:38,380 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 14:57:38,380 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-20 14:57:38,380 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",570,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",20537,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",47461,"local"]] 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 77037 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 68568 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 68568 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-20 14:57:38,381 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-20 14:57:38,382 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 47461 2024-11-20 14:57:38,382 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-20 14:57:38,382 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-20 14:57:38,383 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-20 14:57:38,383 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-20 14:57:38,383 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 96725 2024-11-20 14:57:38,383 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732114658.3839395 2024-11-20 14:57:38,384 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732114658.3839395 2024-11-20 14:57:38,384 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732114658.3839395 2024-11-20 14:57:38,384 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732114658.3846004 2024-11-20 14:57:38,384 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732114658.3846004 2024-11-20 14:57:38,384 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732114658.3846004 2024-11-20 14:57:38,384 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-20 14:57:38,384 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 14:57:38,385 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-20 14:57:38,385 DEBUG (StoredReport) [MainThread] Finish report 2024-11-20 14:57:38,385 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 8.869171142578125e-05}, 'BuildResultsListener': {'duration_sec': 0.0013670921325683594}} 2024-11-20 14:57:38,385 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-20 14:57:38,385 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 14:57:38,386 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 14:57:38,386 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 14:57:38,386 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 14:57:38,386 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 14:57:38,386 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 14:57:38,386 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 14:57:38,386 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 14:57:38,386 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-20 14:57:38,387 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-20 14:57:38,387 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 14:57:38,387 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732114658.3875058 2024-11-20 14:57:38,387 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732114658.3875058 2024-11-20 14:57:38,387 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732114658.3875058 2024-11-20 14:57:38,388 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-20 14:57:38,388 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732114658.3886738 2024-11-20 14:57:38,388 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732114658.3886738 2024-11-20 14:57:38,388 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732114658.3886738 2024-11-20 14:57:38,388 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732114658.388869 2024-11-20 14:57:38,388 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732114658.388869 2024-11-20 14:57:38,388 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732114658.388869 2024-11-20 14:57:38,389 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732114658.389429 2024-11-20 14:57:38,389 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732114658.389429 2024-11-20 14:57:38,389 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732114658.389429 2024-11-20 14:57:38,389 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732114658.3895924 2024-11-20 14:57:38,389 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732114658.3895924 2024-11-20 14:57:38,389 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732114658.3895924 2024-11-20 14:57:38,389 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732114658.3897698 2024-11-20 14:57:38,389 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732114658.3897698 2024-11-20 14:57:38,389 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732114658.3897698 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000084 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000027 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000003 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000017 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000008 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000001 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000004 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000008 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000014 2024-11-20 14:57:38,390 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000008 2024-11-20 14:57:38,391 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000001 2024-11-20 14:57:38,391 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000002 2024-11-20 14:57:38,391 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 14:57:38,391 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732114578.2283564.kf4ijaka 2024-11-20 14:57:38,391 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-20 14:57:38,391 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000609 2024-11-20 14:57:38,391 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-20 14:57:38,391 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000005 2024-11-20 14:57:38,392 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000006 2024-11-20 14:57:38,392 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000009 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.000941 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000007 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000004 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000005 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000004 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000008 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000004 2024-11-20 14:57:38,393 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])