2024-11-20 08:05:49,852 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-20 08:05:49,852 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-20 08:05:49,852 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000759 2024-11-20 08:05:49,853 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 08:05:49,853 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:05:49,853 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:05:49,853 DEBUG (root) [MainThread] vcs type: git 2024-11-20 08:05:49,853 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000200 2024-11-20 08:05:49,853 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,853 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000416 2024-11-20 08:05:49,853 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-20 08:05:49,853 DEBUG (root) [MainThread] Start up timestamp 2024-11-20T08:05:49.UTC (2024-11-20T08:05:49.UTC) 2024-11-20 08:05:49,853 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000036 2024-11-20 08:05:49,853 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000007 2024-11-20 08:05:49,854 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000389 2024-11-20 08:05:49,854 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0 instead of None 2024-11-20 08:05:49,854 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000125 2024-11-20 08:05:49,854 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732089949.8544881 2024-11-20 08:05:49,854 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732089949.8544881 2024-11-20 08:05:49,854 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732089949.8544881 2024-11-20 08:05:49,854 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732089949.8549488 2024-11-20 08:05:49,854 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732089949.8549488 2024-11-20 08:05:49,855 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732089949.8549488 2024-11-20 08:05:49,855 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-20 08:05:49,867 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-20 08:05:49,867 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-20 08:05:49,868 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-20 08:05:49,868 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 08:05:49,868 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-20 08:05:49,868 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-20 08:05:49,868 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-20 08:05:49,869 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-20 08:05:49,869 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 08:05:49,869 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 08:05:49,869 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-20 08:05:49,880 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 08:05:49,880 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 08:05:49,881 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732089949.8814669 2024-11-20 08:05:49,881 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732089949.8814669 2024-11-20 08:05:49,881 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732089949.8814669 2024-11-20 08:05:49,881 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732089949.881595 2024-11-20 08:05:49,881 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732089949.881595 2024-11-20 08:05:49,881 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732089949.881595 2024-11-20 08:05:49,881 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 08:05:49,882 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:05:49,882 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-20 08:05:49,882 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-20 08:05:49,882 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000269 2024-11-20 08:05:49,884 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,884 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:05:49,884 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 08:05:49,884 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,884 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 08:05:49,884 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,884 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002263 2024-11-20 08:05:49,884 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000214 2024-11-20 08:05:49,884 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000045 2024-11-20 08:05:49,885 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,885 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000902 2024-11-20 08:05:49,885 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000032 2024-11-20 08:05:49,886 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 08:05:49,886 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:05:49,886 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:05:49,886 DEBUG (root) [MainThread] vcs type: git 2024-11-20 08:05:49,886 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000218 2024-11-20 08:05:49,886 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-20 08:05:49,886 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-20 08:05:49,886 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000098 2024-11-20 08:05:49,886 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-20 08:05:49,886 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000075 2024-11-20 08:05:49,886 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-20 08:05:49,886 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000002 2024-11-20 08:05:49,887 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:05:49,887 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:05:49,887 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:05:49,887 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-20 08:05:49,887 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001117 2024-11-20 08:05:49,887 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000061 2024-11-20 08:05:49,887 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-20 08:05:49,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000149 2024-11-20 08:05:49,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-20 08:05:49,888 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-20 08:05:49,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000037 2024-11-20 08:05:49,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000004 2024-11-20 08:05:49,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000234 2024-11-20 08:05:49,888 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-20 08:05:49,889 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000384 2024-11-20 08:05:49,929 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.040332 2024-11-20 08:05:49,929 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-20 08:05:49,930 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732089949.9301221 2024-11-20 08:05:49,930 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732089949.9301221 2024-11-20 08:05:49,930 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732089949.9301221 2024-11-20 08:05:49,930 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732089949.9302819 2024-11-20 08:05:49,930 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732089949.9302819 2024-11-20 08:05:49,930 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732089949.9302819 2024-11-20 08:05:50,057 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732089950.0576212 2024-11-20 08:05:50,057 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732089950.0576212 2024-11-20 08:05:50,057 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732089950.0576212 2024-11-20 08:05:50,057 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732089950.057847 2024-11-20 08:05:50,057 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732089950.057847 2024-11-20 08:05:50,057 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732089950.057847 2024-11-20 08:05:50,057 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732089950.0579717 2024-11-20 08:05:50,058 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732089950.0579717 2024-11-20 08:05:50,058 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732089950.0579717 2024-11-20 08:05:50,058 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-20 08:05:50,058 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-20 08:05:50,058 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-20 08:05:50,058 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732089950.0581875 2024-11-20 08:05:50,058 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732089950.0581875 2024-11-20 08:05:50,058 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732089950.0581875 2024-11-20 08:05:50,058 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732089950.0584345 2024-11-20 08:05:50,058 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732089950.0584345 2024-11-20 08:05:50,058 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732089950.0584345 2024-11-20 08:05:50,060 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-20 08:05:50,060 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 08:05:50,060 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-20 08:05:50,063 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.5iAqPtpLrW' file to obtain bazel remote password 2024-11-20 08:05:50,063 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732089950.063142 2024-11-20 08:05:50,063 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732089950.063142 2024-11-20 08:05:50,063 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732089950.063142 2024-11-20 08:05:50,063 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-20 08:05:50,064 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-20 08:05:50,064 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 08:05:50,064 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:05:50,065 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:05:50,065 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-20 08:05:50,067 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-20 08:05:50,069 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 08:05:50,069 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 08:05:50,069 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 08:05:50,069 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-20 08:05:50,070 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-20 08:05:50,070 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 08:05:50,070 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 08:05:50,070 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 08:05:50,070 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-20 08:05:50,071 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-20 08:05:50,080 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-20 08:05:50,080 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-20 08:05:50,081 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 08:05:50,081 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 08:05:50,081 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 08:05:50,081 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-20 08:05:50,081 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 08:05:50,081 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 08:05:50,081 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-20 08:05:50,081 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-20 08:05:50,082 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 08:05:50,082 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 08:05:50,082 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 08:05:50,086 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2364, 12916, 'unix:/home/runner/.ya/tools/v4/psingzGL892.tmp') 2024-11-20 08:05:50,091 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:05:50,091 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-20 08:05:50,091 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-20 08:05:50,091 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.021961688995361328 2024-11-20 08:05:50,092 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.0922396 2024-11-20 08:05:50,092 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_started=1732089950.0922396 2024-11-20 08:05:50,092 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_started - 1732089950.0922396 2024-11-20 08:05:50,093 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.0926604 2024-11-20 08:05:50,093 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started=1732089950.0926604 2024-11-20 08:05:50,093 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started - 1732089950.0926604 2024-11-20 08:05:50,093 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732089950.0927787 2024-11-20 08:05:50,093 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732089950.0927787 2024-11-20 08:05:50,093 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732089950.0927787 2024-11-20 08:05:50,093 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.093464 2024-11-20 08:05:50,093 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started=1732089950.093464 2024-11-20 08:05:50,094 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started - 1732089950.093464 2024-11-20 08:05:50,094 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-20 08:05:50,094 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.0934985 2024-11-20 08:05:50,094 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started=1732089950.0934985 2024-11-20 08:05:50,094 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started - 1732089950.0934985 2024-11-20 08:05:50,094 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-20 08:05:50,094 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732089950.0937588 2024-11-20 08:05:50,095 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732089950.0937588 2024-11-20 08:05:50,095 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732089950.0937588 2024-11-20 08:05:50,095 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 08:05:50,097 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-20 08:05:50,100 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'8d0bd2db4e2d31b3e765e9ba904f22d760b36726', author:b's-sabitova ', summary:b'Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0', svn_id:b'' 2024-11-20 08:05:50,101 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-qakolzqase Host information: Linux ghrun-qakolzqase 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-20 08:05:50,101 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"8d0bd2db4e2d31b3e765e9ba904f22d760b36726\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"s-sabitova \",\n \"ARCADIA_SOURCE_LAST_CHANGE\": -1,\n \"ARCADIA_SOURCE_PATH\": \"/home/runner/actions_runner/_work/ydb/ydb\",\n \"ARCADIA_SOURCE_REVISION\": -1,\n \"ARCADIA_SOURCE_URL\": \"\",\n \"ARCADIA_TAG\": \"\",\n \"BRANCH\": \"\",\n \"BUILD_DATE\": \"2024-11-20T08:05:50.000000Z\",\n \"BUILD_HOST\": \"ghrun-qakolzqase\",\n \"BUILD_TIMESTAMP\": 1732089950,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: 8d0bd2db4e2d31b3e765e9ba904f22d760b36726\\n Branch: \\n Author: s-sabitova \\n Summary: Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\n\\nOther info:\\n Build by: runner\\n Top src dir: /home/runner/actions_runner/_work/ydb/ydb\\n Top build dir: /home/runner/.ya/build\\n Hostname: ghrun-qakolzqase\\n Host information: \\n Linux ghrun-qakolzqase 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: 8d0bd2db4e2d31b3e765e9ba904f22d760b36726\\n Branch: \\n Author: s-sabitova \\n Summary: Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\n\",\n \"VCS\": \"git\"\n}" 2024-11-20 08:05:50,101 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.037438154220581055 2024-11-20 08:05:50,130 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 08:05:50,131 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 08:05:50,261 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 08:05:50,261 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-20 08:05:50,261 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.2619233 2024-11-20 08:05:50,261 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished=1732089950.2619233 2024-11-20 08:05:50,262 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732089950.2619233 2024-11-20 08:05:50,262 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.262119 2024-11-20 08:05:50,262 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started=1732089950.262119 2024-11-20 08:05:50,262 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started - 1732089950.262119 2024-11-20 08:05:50,262 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 0x7f40fe97b740>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/makefiles.dart'} 2024-11-20 08:05:50,262 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-20 08:05:50,262 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-20 08:05:50,262 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 08:05:50,262 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-20 08:05:50,263 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/1732089949.2938.p2vu6yj0/yatmpkxrya26c --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/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 08:05:50,263 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 08:05:50,265 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-20 08:05:50,265 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.2655792 2024-11-20 08:05:50,265 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished=1732089950.2655792 2024-11-20 08:05:50,265 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished - 1732089950.2655792 2024-11-20 08:05:50,265 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.2658374 2024-11-20 08:05:50,265 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started=1732089950.2658374 2024-11-20 08:05:50,265 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started - 1732089950.2658374 2024-11-20 08:05:50,266 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 0x7f40ff66c900>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/makefiles.dart'} 2024-11-20 08:05:50,266 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-20 08:05:50,266 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-20 08:05:50,266 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-20 08:05:50,266 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/1732089949.2938.p2vu6yj0/yatmpqjkcg4xf --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/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 08:05:50,269 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732089950268914,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 08:05:50,269 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950269024,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,269 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732089950269057,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,269 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950269066,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,269 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732089950269071,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,270 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732089950269325,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,270 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732089950269330,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732089950272097,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950272184,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732089950272215,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950272223,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732089950272227,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,272 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732089950272494,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,273 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732089950272499,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,428 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732089950427625,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,428 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732089950427652,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,429 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732089950429024,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,429 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732089950429035,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,430 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732089950430211,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,430 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732089950430219,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,432 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732089950432224,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,432 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732089950432239,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,433 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732089950433569,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,433 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732089950433580,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,434 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732089950434773,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,434 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732089950434781,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,548 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732089950547950,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,553 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732089950552679,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,580 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580383,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,580 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580401,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580406,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580488,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732089950580492,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580544,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580549,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580553,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,581 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580556,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580560,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580564,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580567,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950580571,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732089950581395,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950581404,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732089950581412,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732089950581416,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,582 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732089950581420,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,583 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950581425,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,583 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732089950581429,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-20 08:05:50,583 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732089950581433,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-20 08:05:50,583 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732089950581437,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585110,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585127,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585132,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585204,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732089950585208,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,585 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585246,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585251,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585255,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585258,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585262,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585265,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585269,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950585272,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732089950586066,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950586074,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,586 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732089950586080,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732089950586084,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732089950586088,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950586092,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732089950586096,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732089950586100,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-20 08:05:50,587 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732089950586104,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.09622399525,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732089950677671,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732089950677692,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950677700,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950677706,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732089950677754,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,678 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":3041,"Sub":"","Type":"Debug","_timestamp":1732089950677832,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,678 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732089950677836,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9021773; load time = 9440; loaded MD5 time = 776; Max loaded MD5 time = 5; loaded count = 2174; max load time = 17; mapped size = 40632376; mapped MD5 time = 7222; Max mapped MD5 time = 462; mapped count = 130; map time = 659; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3341; lstat min us = 0; lstat avr us = 1; lstat max us = 113; 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 = 2189; readdir min us = 0; readdir avr us = 0; readdir max us = 39; (opendir + readdir) sum us = 2189; (lstat + opendir + readdir) sum us = 5530;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732089950677868,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09291900644,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732089950679033,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2189,"LoadTime":9440,"LoadedCount":2174,"LoadedMD5Time":776,"LoadedSize":9021773,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5530,"LstatMaxUs":113,"LstatMinUs":0,"LstatSumUs":3341,"MapTime":659,"MappedCount":130,"MappedMD5Time":7222,"MappedSize":40632376,"MaxLoadTime":17,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":462,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":39,"ReaddirMinUs":0,"ReaddirSumUs":2189,"_timestamp":1732089950677880,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732089950679053,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9021773,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732089950677891,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,679 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950679061,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9440,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732089950677895,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950679068,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732089950677898,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732089950679114,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732089950677901,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,680 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":3043,"Sub":"","Type":"Debug","_timestamp":1732089950679194,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":659,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732089950677903,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732089950679198,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732089950677906,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,680 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732089950677909,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9021773; load time = 8879; loaded MD5 time = 729; Max loaded MD5 time = 16; loaded count = 2174; max load time = 25; mapped size = 40632376; mapped MD5 time = 5946; Max mapped MD5 time = 385; mapped count = 130; map time = 628; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3112; lstat min us = 0; lstat avr us = 1; lstat max us = 15; 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 = 1877; readdir min us = 0; readdir avr us = 0; readdir max us = 37; (opendir + readdir) sum us = 1877; (lstat + opendir + readdir) sum us = 4989;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732089950679230,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9021773; load time = 9440; loaded count = 2174; max load time = 17;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732089950677916,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":9440,"LoadedCount":2174,"LoadedSize":9021773,"MaxLoadTime":17,"_timestamp":1732089950677921,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":1877,"LoadTime":8879,"LoadedCount":2174,"LoadedMD5Time":729,"LoadedSize":9021773,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":4989,"LstatMaxUs":15,"LstatMinUs":0,"LstatSumUs":3112,"MapTime":628,"MappedCount":130,"MappedMD5Time":5946,"MappedSize":40632376,"MaxLoadTime":25,"MaxLoadedMD5Time":16,"MaxMappedMD5Time":385,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":37,"ReaddirMinUs":0,"ReaddirSumUs":1877,"_timestamp":1732089950679242,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 1; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732089950677931,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9021773,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732089950679254,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":1,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732089950677936,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-20 08:05:50,681 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":8879,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732089950679258,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,681 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":1732089950677949,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732089950679261,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732089950677954,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732089950679264,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,682 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":1732089950677968,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":628,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732089950679267,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732089950677972,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732089950679269,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732089950677977,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732089950679272,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,682 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732089950677980,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9021773; load time = 8879; loaded count = 2174; max load time = 25;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732089950679279,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,683 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":1732089950677987,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":8879,"LoadedCount":2174,"LoadedSize":9021773,"MaxLoadTime":25,"_timestamp":1732089950679284,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732089950677991,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 1; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732089950679295,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732089950677994,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,683 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":1,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732089950679300,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-20 08:05:50,683 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":1732089950678086,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,683 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":1732089950679312,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732089950678090,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732089950679317,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732089950678094,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,684 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":1732089950679332,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732089950678097,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732089950679335,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732089950678100,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732089950679340,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732089950678103,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,684 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732089950679343,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-20 08:05:50,685 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":1732089950679351,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732089950678112,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732089950679355,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732089950678714,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732089950679358,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732089950678716,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,685 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":1732089950679461,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732089950681335,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732089950679466,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732089950681338,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,685 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732089950679470,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732089950679474,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732089950681388,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732089950679477,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732089950681391,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,686 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-relwithdebinfo-nopic). Tool targets: set() 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732089950679479,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732089950681393,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,686 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732089950681395,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732089950681398,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732089950679489,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732089950681400,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732089950680106,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732089950681456,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732089950680109,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732089950681459,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732089950682757,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,687 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":1732089950682357,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,687 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732089950682759,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,688 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":1732089950682367,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-20 08:05:50,688 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-relwithdebinfo-pic). Tool targets: set() 2024-11-20 08:05:50,688 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732089950.6882148 2024-11-20 08:05:50,688 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950682375,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,688 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732089950.6882148 2024-11-20 08:05:50,688 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732089950.6882148 2024-11-20 08:05:50,688 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-20 08:05:50,688 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732089950.6887417 2024-11-20 08:05:50,688 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732089950.6887417 2024-11-20 08:05:50,688 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732089950.6887417 2024-11-20 08:05:50,688 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732089950.6889439 2024-11-20 08:05:50,689 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732089950.6889439 2024-11-20 08:05:50,689 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732089950.6889439 2024-11-20 08:05:50,689 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732089950.6891484 2024-11-20 08:05:50,689 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732089950.6891484 2024-11-20 08:05:50,689 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732089950.6891484 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732089950682808,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950682380,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732089950682810,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732089950683527,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732089950682812,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732089950683533,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732089950682814,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,689 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732089950683971,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732089950682817,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732089950684003,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732089950682819,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732089950684005,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732089950682874,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732089950684476,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732089950682876,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950684622,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,690 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":1732089950683760,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,690 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732089950684627,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,691 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":1732089950683772,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732089950684629,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950683779,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950684632,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950683785,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732089950684665,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732089950684813,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732089950684668,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732089950684818,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732089950684671,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,691 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732089950685249,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732089950684809,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732089950685280,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732089950684831,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732089950685283,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732089950685533,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732089950685735,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732089950685588,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950685861,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732089950685594,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,692 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732089950685865,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950685598,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732089950685868,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732089950685602,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950685871,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732089950688813,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732089950685904,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732089950688819,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732089950685906,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732089950685910,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950688830,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,693 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732089950686051,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732089950686071,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732089950686760,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732089950686817,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732089950686822,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950686826,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732089950686830,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732089950689989,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732089950689995,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,694 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950690005,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,696 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":1732089950696023,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,696 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":1732089950696032,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-20 08:05:50,696 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950696052,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,696 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.00723832408,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732089950696061,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,696 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732089950696065,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,696 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732089950696068,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:05:50,696 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950696777,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,697 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732089950696783,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,697 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732089950696794,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-20 08:05:50,697 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":1732089950696806,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,697 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":1732089950697185,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,697 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":1732089950696812,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-20 08:05:50,697 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":1732089950697193,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-20 08:05:50,697 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01128263304,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732089950696879,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,697 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950697201,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,697 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732089950696883,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.00721057443,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732089950697209,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732089950696904,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732089950697214,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732089950696907,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732089950697216,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:05:50,698 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":1732089950696914,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950697926,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732089950696918,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732089950697932,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,698 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732089950697943,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-20 08:05:50,699 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":1732089950697955,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,699 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":1732089950697961,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-20 08:05:50,699 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01120319315,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732089950698029,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,699 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732089950698033,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,699 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732089950698055,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,699 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732089950698058,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,699 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":1732089950698065,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,699 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732089950698070,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950701358,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950701374,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732089950701379,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950701392,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950701760,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,701 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3041,"Sub":"","Type":"Debug","_timestamp":1732089950701404,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950701775,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732089950701996,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732089950701780,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4330734179,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732089950702003,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950701791,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732089950702008,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732089950701803,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732089950702385,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:05:50,702 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4302755578,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732089950702391,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:05:50,703 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732089950702396,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-20 08:05:50,707 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/1732089949.2938.p2vu6yj0/yatmpkxrya26c --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/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 08:05:50,707 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/1732089949.2938.p2vu6yj0/yatmpqjkcg4xf --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/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 08:05:50,707 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732089950.2625572681427,"finish":1732089950.26307487487793,"duration":0.000517606735229},"execution":{"start":1732089950.263075590133667,"finish":1732089950.707189798355102,"duration":0.444114208221436},"postprocessing":{"start":1732089950.707193374633789,"finish":1732089950.707353830337524,"duration":0.000160455703735}},"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":1732089950.268913984298706,"finish":1732089950.702008008956909,"duration":0.433094024658203},"Load configuration from cache":{"start":1732089950.269057035446167,"finish":1732089950.269071102142334,"duration":0.000014066696167},"Load configuration (no cache)":{"start":1732089950.269330024719238,"finish":1732089950.427624940872192,"duration":0.158294916152954},"Compile and RecalcAll conditions":{"start":1732089950.427651882171631,"finish":1732089950.42902398109436,"duration":0.001372098922729},"Fold global commands":{"start":1732089950.429034948348999,"finish":1732089950.430211067199707,"duration":0.001176118850708},"Load plugins":{"start":1732089950.430218935012817,"finish":1732089950.547950029373169,"duration":0.117731094360352},"ymake main":{"start":1732089950.58049201965332,"finish":1732089950.701996088027954,"duration":0.121504068374634},"Build graph":{"start":1732089950.581419944763184,"finish":1732089950.677994012832642,"duration":0.096574068069458},"Configure graph":{"start":1732089950.581437110900879,"finish":1732089950.67769193649292,"duration":0.096254825592041},"Find Lost Includes":{"start":1732089950.677753925323486,"finish":1732089950.677835941314697,"duration":0.000082015991211},"Set reachable nodes":{"start":1732089950.678112030029297,"finish":1732089950.678714036941528,"duration":0.000602006912231},"Sort edges":{"start":1732089950.678715944290161,"finish":1732089950.681334972381592,"duration":0.002619028091431},"Report Foreign Platform Events":{"start":1732089950.68133807182312,"finish":1732089950.681387901306152,"duration":0.000049829483032},"Check blacklist":{"start":1732089950.681391000747681,"finish":1732089950.681392908096314,"duration":0.000001907348633},"Check isolated projects":{"start":1732089950.681395053863525,"finish":1732089950.681397914886475,"duration":0.000002861022949},"Report Configure Events":{"start":1732089950.681400060653686,"finish":1732089950.681456089019775,"duration":0.000056028366089},"Save and compact":{"start":1732089950.681458950042725,"finish":1732089950.683526992797852,"duration":0.002068042755127},"Detect loops":{"start":1732089950.683532953262329,"finish":1732089950.684003114700317,"duration":0.000470161437988},"Apply Dependency Management":{"start":1732089950.68400502204895,"finish":1732089950.684628963470459,"duration":0.000623941421509},"Save Dependency management cache":{"start":1732089950.684475898742676,"finish":1732089950.684627056121826,"duration":0.00015115737915},"Fill package outputs":{"start":1732089950.684664964675903,"finish":1732089950.684668064117432,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732089950.684670925140381,"finish":1732089950.684808969497681,"duration":0.0001380443573},"Find missing peerdirs":{"start":1732089950.684830904006958,"finish":1732089950.685533046722412,"duration":0.000702142715454},"Export JSON":{"start":1732089950.685587882995606,"finish":1732089950.696903944015503,"duration":0.011316061019897},"Write JSON":{"start":1732089950.685594081878662,"finish":1732089950.696882963180542,"duration":0.01128888130188},"Visit JSON":{"start":1732089950.685601949691772,"finish":1732089950.688812971115112,"duration":0.00321102142334},"Render JSON":{"start":1732089950.68881893157959,"finish":1732089950.696064949035644,"duration":0.007246017456055},"Save Uids cache":{"start":1732089950.696068048477173,"finish":1732089950.696783065795898,"duration":0.000715017318726}},"metrics":{"File access":{"loaded size":9021773,"load time":9440,"loaded MD5 time":776,"Max loaded MD5 time":5,"loaded count":2174,"max load time":17,"mapped size":40632376,"mapped MD5 time":7222,"Max mapped MD5 time":462,"mapped count":130,"map time":659,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3341,"lstat min us":0,"lstat avr us":1,"lstat max us":113,"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":2189,"readdir min us":0,"readdir avr us":0,"readdir max us":39,"(opendir + readdir) sum us":2189,"(lstat + opendir + readdir) sum us":5530},"Parsing":{"parse time":1,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/yatmpkxrya26c","--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/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.yftxs5tn/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.444114208221436} 2024-11-20 08:05:50,708 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732089950.7082794 2024-11-20 08:05:50,708 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732089950.7082794 2024-11-20 08:05:50,708 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732089950.7082794 2024-11-20 08:05:50,708 DEBUG (core.event_handling.base_subscriber) [Misc-02] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 08:05:50,709 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732089950.266263246536255,"finish":1732089950.266759872436523,"duration":0.000496625900269},"execution":{"start":1732089950.266760587692261,"finish":1732089950.708102464675903,"duration":0.441341876983643},"postprocessing":{"start":1732089950.708104133605957,"finish":1732089950.708693027496338,"duration":0.000588893890381}},"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":1732089950.272097110748291,"finish":1732089950.702395915985107,"duration":0.430298805236816},"Load configuration from cache":{"start":1732089950.272214889526367,"finish":1732089950.272227048873901,"duration":0.000012159347534},"Load configuration (no cache)":{"start":1732089950.272499084472656,"finish":1732089950.432224035263062,"duration":0.159724950790405},"Compile and RecalcAll conditions":{"start":1732089950.432239055633545,"finish":1732089950.433568954467773,"duration":0.001329898834229},"Fold global commands":{"start":1732089950.433579921722412,"finish":1732089950.434772968292236,"duration":0.001193046569824},"Load plugins":{"start":1732089950.434781074523926,"finish":1732089950.552679061889648,"duration":0.117897987365723},"ymake main":{"start":1732089950.585207939147949,"finish":1732089950.702384948730469,"duration":0.11717700958252},"Build graph":{"start":1732089950.586087942123413,"finish":1732089950.679358005523682,"duration":0.093270063400269},"Configure graph":{"start":1732089950.586103916168213,"finish":1732089950.679053068161011,"duration":0.092949151992798},"Find Lost Includes":{"start":1732089950.679114103317261,"finish":1732089950.679198026657104,"duration":0.000083923339844},"Set reachable nodes":{"start":1732089950.679488897323608,"finish":1732089950.680105924606323,"duration":0.000617027282715},"Sort edges":{"start":1732089950.680109024047852,"finish":1732089950.682756900787354,"duration":0.002647876739502},"Report Foreign Platform Events":{"start":1732089950.682759046554565,"finish":1732089950.682807922363281,"duration":0.000048875808716},"Check blacklist":{"start":1732089950.682810068130493,"finish":1732089950.682811975479126,"duration":0.000001907348633},"Check isolated projects":{"start":1732089950.682813882827759,"finish":1732089950.682816982269287,"duration":0.000003099441528},"Report Configure Events":{"start":1732089950.68281888961792,"finish":1732089950.682873964309692,"duration":0.000055074691772},"Save and compact":{"start":1732089950.682876110076904,"finish":1732089950.684813022613525,"duration":0.001936912536621},"Detect loops":{"start":1732089950.684818029403686,"finish":1732089950.685280084609985,"duration":0.000462055206299},"Apply Dependency Management":{"start":1732089950.685282945632935,"finish":1732089950.68586802482605,"duration":0.000585079193115},"Save Dependency management cache":{"start":1732089950.685734987258911,"finish":1732089950.685864925384522,"duration":0.00012993812561},"Fill package outputs":{"start":1732089950.685904026031494,"finish":1732089950.685905933380127,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732089950.685909986495972,"finish":1732089950.686050891876221,"duration":0.000140905380249},"Find missing peerdirs":{"start":1732089950.686070919036865,"finish":1732089950.686759948730469,"duration":0.000689029693604},"Export JSON":{"start":1732089950.686816930770874,"finish":1732089950.698055028915405,"duration":0.011238098144531},"Write JSON":{"start":1732089950.686821937561035,"finish":1732089950.698033094406128,"duration":0.011211156845093},"Visit JSON":{"start":1732089950.686830043792725,"finish":1732089950.68998908996582,"duration":0.003159046173096},"Render JSON":{"start":1732089950.689995050430298,"finish":1732089950.697213888168335,"duration":0.007218837738037},"Save Uids cache":{"start":1732089950.697216033935547,"finish":1732089950.697932004928589,"duration":0.000715970993042}},"metrics":{"File access":{"loaded size":9021773,"load time":8879,"loaded MD5 time":729,"Max loaded MD5 time":16,"loaded count":2174,"max load time":25,"mapped size":40632376,"mapped MD5 time":5946,"Max mapped MD5 time":385,"mapped count":130,"map time":628,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3112,"lstat min us":0,"lstat avr us":1,"lstat max us":15,"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":1877,"readdir min us":0,"readdir avr us":0,"readdir max us":37,"(opendir + readdir) sum us":1877,"(lstat + opendir + readdir) sum us":4989},"Parsing":{"parse time":1,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/yatmpqjkcg4xf","--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/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.ts_4mzm3/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.441341876983643} 2024-11-20 08:05:50,709 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732089950.7092564 2024-11-20 08:05:50,709 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732089950.7092564 2024-11-20 08:05:50,709 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732089950.7092564 2024-11-20 08:05:50,709 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732089950.7094426 2024-11-20 08:05:50,709 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732089950.7094426 2024-11-20 08:05:50,709 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732089950.7094426 2024-11-20 08:05:50,709 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.7096899 2024-11-20 08:05:50,709 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished=1732089950.7096899 2024-11-20 08:05:50,709 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732089950.7096899 2024-11-20 08:05:50,710 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.7099862 2024-11-20 08:05:50,710 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started=1732089950.7099862 2024-11-20 08:05:50,710 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started - 1732089950.7099862 2024-11-20 08:05:50,710 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732089950.7101657 2024-11-20 08:05:50,710 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732089950.7101657 2024-11-20 08:05:50,710 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732089950.7101657 2024-11-20 08:05:50,710 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.710442 2024-11-20 08:05:50,710 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished=1732089950.710442 2024-11-20 08:05:50,710 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished - 1732089950.710442 2024-11-20 08:05:50,710 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.7107148 2024-11-20 08:05:50,710 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_started=1732089950.7107148 2024-11-20 08:05:50,710 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_started - 1732089950.7107148 2024-11-20 08:05:50,715 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-20 08:05:50,715 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-20 08:05:50,715 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.00039315223693847656 2024-11-20 08:05:50,715 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.00025963783264160156 2024-11-20 08:05:50,715 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.7158272 2024-11-20 08:05:50,715 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished=1732089950.7158272 2024-11-20 08:05:50,716 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732089950.7158272 2024-11-20 08:05:50,716 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.7159348 2024-11-20 08:05:50,716 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished=1732089950.7159348 2024-11-20 08:05:50,716 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished - 1732089950.7159348 2024-11-20 08:05:50,716 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732089950.7164388 2024-11-20 08:05:50,716 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished=1732089950.7164388 2024-11-20 08:05:50,716 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732089950.7164388 2024-11-20 08:05:50,716 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732089950.7165985 2024-11-20 08:05:50,716 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished=1732089950.7165985 2024-11-20 08:05:50,717 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished - 1732089950.7165985 2024-11-20 08:05:50,717 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-relwithdebinfo: 0.028085708618164062 2024-11-20 08:05:50,717 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732089950.7173483 2024-11-20 08:05:50,717 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732089950.7173483 2024-11-20 08:05:50,717 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732089950.7173483 2024-11-20 08:05:50,717 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732089950.7176175 2024-11-20 08:05:50,717 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732089950.7176175 2024-11-20 08:05:50,717 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732089950.7176175 2024-11-20 08:05:50,717 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-relwithdebinfo: 0.0005338191986083984 2024-11-20 08:05:50,717 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732089950.71781 2024-11-20 08:05:50,717 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732089950.71781 2024-11-20 08:05:50,717 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732089950.71781 2024-11-20 08:05:50,717 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732089950.7179558 2024-11-20 08:05:50,718 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732089950.7179558 2024-11-20 08:05:50,718 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732089950.7179558 2024-11-20 08:05:50,718 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-relwithdebinfo: 0.0003597736358642578 2024-11-20 08:05:50,718 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-20 08:05:50,718 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732089950.718214 2024-11-20 08:05:50,718 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732089950.718214 2024-11-20 08:05:50,718 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732089950.718214 2024-11-20 08:05:50,718 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732089950.71837 2024-11-20 08:05:50,718 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732089950.71837 2024-11-20 08:05:50,718 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732089950.71837 2024-11-20 08:05:50,718 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-relwithdebinfo: 0.0003819465637207031 2024-11-20 08:05:50,718 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-20 08:05:50,718 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732089950.7185879 2024-11-20 08:05:50,718 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732089950.7185879 2024-11-20 08:05:50,718 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732089950.7185879 2024-11-20 08:05:50,718 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-20 08:05:50,719 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732089950.719315 2024-11-20 08:05:50,719 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732089950.719315 2024-11-20 08:05:50,719 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732089950.719315 2024-11-20 08:05:50,719 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732089950.719798 2024-11-20 08:05:50,719 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732089950.719798 2024-11-20 08:05:50,719 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732089950.719798 2024-11-20 08:05:50,719 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-20 08:05:50,734 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 08:05:50,858 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 08:05:50,858 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-20 08:05:50,858 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732089950.8589215 2024-11-20 08:05:50,858 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732089950.8589215 2024-11-20 08:05:50,859 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732089950.8589215 2024-11-20 08:05:50,859 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732089950.859116 2024-11-20 08:05:50,859 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732089950.859116 2024-11-20 08:05:50,859 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732089950.859116 2024-11-20 08:05:50,859 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 0x7f40fe93b880>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/makefiles.dart'} 2024-11-20 08:05:50,859 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-20 08:05:50,859 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-20 08:05:50,859 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 08:05:50,859 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-20 08:05:50,860 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/1732089949.2938.p2vu6yj0/yatmp7cnw0cha --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/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/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 08:05:51,225 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/1732089949.2938.p2vu6yj0/yatmp7cnw0cha --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/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/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 08:05:51,226 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 08:05:51,226 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732089950.859519004821777,"finish":1732089950.860144376754761,"duration":0.000625371932983},"execution":{"start":1732089950.860144853591919,"finish":1732089951.226123809814453,"duration":0.365978956222534},"postprocessing":{"start":1732089951.22612738609314,"finish":1732089951.226464986801147,"duration":0.000337600708008}},"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/1732089949.2938.p2vu6yj0/yatmp7cnw0cha","--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/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/gen_graph.lt77hokb/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.365978956222534} 2024-11-20 08:05:51,226 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732089951.2268677 2024-11-20 08:05:51,226 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732089951.2268677 2024-11-20 08:05:51,227 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732089951.2268677 2024-11-20 08:05:51,227 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732089951.2271273 2024-11-20 08:05:51,227 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732089951.2271273 2024-11-20 08:05:51,227 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732089951.2271273 2024-11-20 08:05:51,227 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732089951.2273262 2024-11-20 08:05:51,227 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732089951.2273262 2024-11-20 08:05:51,227 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732089951.2273262 2024-11-20 08:05:51,227 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732089951.2275946 2024-11-20 08:05:51,227 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732089951.2275946 2024-11-20 08:05:51,227 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732089951.2275946 2024-11-20 08:05:51,228 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732089951.2282188 2024-11-20 08:05:51,228 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732089951.2282188 2024-11-20 08:05:51,228 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732089951.2282188 2024-11-20 08:05:51,229 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732089951.2299743 2024-11-20 08:05:51,230 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732089951.2299743 2024-11-20 08:05:51,230 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732089951.2299743 2024-11-20 08:05:51,230 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732089951.2301865 2024-11-20 08:05:51,230 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732089951.2301865 2024-11-20 08:05:51,230 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732089951.2301865 2024-11-20 08:05:51,230 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732089951.2303503 2024-11-20 08:05:51,230 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732089951.2303503 2024-11-20 08:05:51,230 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732089951.2303503 2024-11-20 08:05:51,231 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732089951.231042 2024-11-20 08:05:51,231 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732089951.231042 2024-11-20 08:05:51,231 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732089951.231042 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f40fe9f1ee0>' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-20 08:05:51,231 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-20 08:05:51,231 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-20 08:05:51,232 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-20 08:05:51,232 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-20 08:05:51,232 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-relwithdebinfo: 0.0010662078857421875 2024-11-20 08:05:51,232 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-relwithdebinfo 2024-11-20 08:05:51,232 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732089951.2323709 2024-11-20 08:05:51,232 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732089951.2323709 2024-11-20 08:05:51,232 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732089951.2323709 2024-11-20 08:05:51,232 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732089951.2325213 2024-11-20 08:05:51,232 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732089951.2325213 2024-11-20 08:05:51,232 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732089951.2325213 2024-11-20 08:05:51,232 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732089951.2326503 2024-11-20 08:05:51,232 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732089951.2326503 2024-11-20 08:05:51,232 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732089951.2326503 2024-11-20 08:05:51,233 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-20 08:05:51,233 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 08:05:51,233 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-20 08:05:51,233 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-20 08:05:51,233 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732089951.233549 2024-11-20 08:05:51,233 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732089951.233549 2024-11-20 08:05:51,233 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732089951.233549 2024-11-20 08:05:51,233 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732089951.2337084 2024-11-20 08:05:51,233 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732089951.2337084 2024-11-20 08:05:51,233 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732089951.2337084 2024-11-20 08:05:51,233 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732089951.2338564 2024-11-20 08:05:51,233 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732089951.2338564 2024-11-20 08:05:51,233 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732089951.2338564 2024-11-20 08:05:51,233 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.1422104835510254 2024-11-20 08:05:51,234 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732089951.234038 2024-11-20 08:05:51,234 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732089951.234038 2024-11-20 08:05:51,234 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732089951.234038 2024-11-20 08:05:51,234 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 08:05:51,234 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00023937225341796875 2024-11-20 08:05:51,234 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732089951.2342691 2024-11-20 08:05:51,234 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732089951.2342691 2024-11-20 08:05:51,234 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732089951.2342691 2024-11-20 08:05:51,234 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.0002009868621826172 2024-11-20 08:05:51,234 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 08:05:51,234 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 08:05:51,234 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732089951.2347095 2024-11-20 08:05:51,234 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732089951.2347095 2024-11-20 08:05:51,234 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732089951.2347095 2024-11-20 08:05:51,234 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-20 08:05:51,234 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.58306884765625e-06 2024-11-20 08:05:51,234 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732089951.234939 2024-11-20 08:05:51,234 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732089951.234939 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732089951.234939 2024-11-20 08:05:51,235 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006449222564697266 2024-11-20 08:05:51,235 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732089951.2351172 2024-11-20 08:05:51,235 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732089951.2351172 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732089951.2351172 2024-11-20 08:05:51,235 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732089951.2352314 2024-11-20 08:05:51,235 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732089951.2352314 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732089951.2352314 2024-11-20 08:05:51,235 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732089951.235362 2024-11-20 08:05:51,235 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732089951.235362 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732089951.235362 2024-11-20 08:05:51,235 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:05:51,235 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:05:51,235 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:05:51,235 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732089951.2356408 2024-11-20 08:05:51,235 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732089951.2356408 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732089951.2356408 2024-11-20 08:05:51,235 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732089951.235785 2024-11-20 08:05:51,235 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732089951.235785 2024-11-20 08:05:51,235 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732089951.235785 2024-11-20 08:05:51,236 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732089951.236093 2024-11-20 08:05:51,236 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732089951.236093 2024-11-20 08:05:51,236 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732089951.236093 2024-11-20 08:05:51,236 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732089951.2362514 2024-11-20 08:05:51,236 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732089951.2362514 2024-11-20 08:05:51,236 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732089951.2362514 2024-11-20 08:05:51,236 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0013246536254882812 2024-11-20 08:05:51,236 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732089951.2364433 2024-11-20 08:05:51,236 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732089951.2364433 2024-11-20 08:05:51,236 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732089951.2364433 2024-11-20 08:05:51,236 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732089951.2366655 2024-11-20 08:05:51,236 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732089951.2366655 2024-11-20 08:05:51,236 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732089951.2366655 2024-11-20 08:05:51,236 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732089951.2369637 2024-11-20 08:05:51,237 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732089951.2369637 2024-11-20 08:05:51,237 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732089951.2369637 2024-11-20 08:05:51,237 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732089951.23711 2024-11-20 08:05:51,237 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732089951.23711 2024-11-20 08:05:51,237 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732089951.23711 2024-11-20 08:05:51,237 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732089951.2372966 2024-11-20 08:05:51,237 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732089951.2372966 2024-11-20 08:05:51,237 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732089951.2372966 2024-11-20 08:05:51,237 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732089951.2374341 2024-11-20 08:05:51,237 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732089951.2374341 2024-11-20 08:05:51,237 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732089951.2374341 2024-11-20 08:05:51,237 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.1792871952056885 2024-11-20 08:05:51,238 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732089951.2389703 2024-11-20 08:05:51,239 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732089951.2389703 2024-11-20 08:05:51,239 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732089951.2389703 2024-11-20 08:05:51,239 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-20 08:05:51,239 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-20 08:05:51,239 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-20 08:05:51,239 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732089951.239256 2024-11-20 08:05:51,239 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732089951.239256 2024-11-20 08:05:51,239 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732089951.239256 2024-11-20 08:05:51,239 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732089951.2394001 2024-11-20 08:05:51,239 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732089951.2394001 2024-11-20 08:05:51,239 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732089951.2394001 2024-11-20 08:05:51,239 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-20 08:05:51,239 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732089951.2396088 2024-11-20 08:05:51,239 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732089951.2396088 2024-11-20 08:05:51,239 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732089951.2396088 2024-11-20 08:05:51,239 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-20 08:05:51,241 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-20 08:05:51,242 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-20 08:05:51,243 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-20 08:05:51,244 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-20 08:05:51,246 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-20 08:05:51,247 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-20 08:05:51,248 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-20 08:05:51,249 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-20 08:05:51,250 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-20 08:05:51,251 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-20 08:05:51,252 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-20 08:05:51,253 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-20 08:05:51,255 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-20 08:05:51,258 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,258 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/3067455a51595241317750497931634732724c63734100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,259 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 08:05:51,259 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/3769554a53624e61733865673555613741466e345a4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,259 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 0gEZQYRA1wPIy1cG2rLcsA => False 2024-11-20 08:05:51,260 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/45496c4c484f70786e4675725a6374484c7a2d47506700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,260 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/777467344d4b4b4950565342647635592d78536d646700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,260 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing 7iUJSbNas8eg5Ua7AFn4ZA => False 2024-11-20 08:05:51,261 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing EIlLHOpxnFurZctHLz-GPg => False 2024-11-20 08:05:51,261 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,261 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing wtg4MKKIPVSBdv5Y-xSmdg => False 2024-11-20 08:05:51,262 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 08:05:51,263 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/52696d5031345a792d344c41376f5634665250796c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,263 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing RimP14Zy-4LA7oV4fRPylw => False 2024-11-20 08:05:51,264 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,264 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 08:05:51,265 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,265 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing iTMgoEEhV9D7LHyD5DWuLw => False 2024-11-20 08:05:51,266 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/3675566751716f6d4c3279683532743357692d354f6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,267 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing 6uVgQqomL2yh52t3Wi-5Og => False 2024-11-20 08:05:51,267 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7a652d367053414577366354443768636b456b46427700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,267 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,267 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing ze-6pSAEw6cTD7hckEkFBw => False 2024-11-20 08:05:51,268 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/7554324d64504d31796451424f6d726e72646437315100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,268 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => False 2024-11-20 08:05:51,268 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing uT2MdPM1ydQBOmrnrdd71Q => False 2024-11-20 08:05:51,269 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-20 08:05:51,269 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 08:05:51,273 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732089951.273086 2024-11-20 08:05:51,273 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732089951.273086 2024-11-20 08:05:51,273 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732089951.273086 2024-11-20 08:05:51,273 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.03558611869812012 2024-11-20 08:05:51,273 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732089951.2735043 2024-11-20 08:05:51,273 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732089951.2735043 2024-11-20 08:05:51,273 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732089951.2735043 2024-11-20 08:05:51,273 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732089951.2736833 2024-11-20 08:05:51,273 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732089951.2736833 2024-11-20 08:05:51,273 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732089951.2736833 2024-11-20 08:05:51,274 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 08:05:51,274 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:05:51,274 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:05:51,274 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:05:51,274 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:05:51,274 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:05:51,274 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 08:05:51,275 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-20 08:05:51,275 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-20 08:05:51,275 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732089951.2757294 2024-11-20 08:05:51,275 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732089951.2757294 2024-11-20 08:05:51,275 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732089951.2757294 2024-11-20 08:05:51,276 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-20 08:05:51,286 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732089949.2938.p2vu6yj0/2938lg92z0yo', '0'] 2024-11-20 08:05:51,287 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-20 08:05:51,311 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/pfsr/STAMP 2024-11-20 08:05:51,311 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.337860107421875e-05 2024-11-20 08:05:51,311 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00013947486877441406 2024-11-20 08:05:51,311 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 6.0558319091796875e-05 2024-11-20 08:05:51,311 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 7.534027099609375e-05 2024-11-20 08:05:51,312 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-20 08:05:51,418 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-20 08:05:51,422 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid uT2MdPM1ydQBOmrnrdd71Q) address: (2519, 18053, 'unix:/home/runner/.ya/build/cache/7/psingTvXNwY.tmp') 2024-11-20 08:05:51,431 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/7554324d64504d31796451424f6d726e72646437315100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,433 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/7a652d367053414577366354443768636b456b46427700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,433 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing uT2MdPM1ydQBOmrnrdd71Q => False 2024-11-20 08:05:51,434 DEBUG (urllib3.connectionpool) [Worker-009] http://cachesrv.internal:8081 "HEAD /ac/45496c4c484f70786e4675725a6374484c7a2d47506700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,434 DEBUG (urllib3.connectionpool) [Worker-010] http://cachesrv.internal:8081 "HEAD /ac/3675566751716f6d4c3279683532743357692d354f6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,437 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing ze-6pSAEw6cTD7hckEkFBw => False 2024-11-20 08:05:51,437 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 0gEZQYRA1wPIy1cG2rLcsA created (provider=None) 2024-11-20 08:05:51,437 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/3067455a51595241317750497931634732724c63734100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,437 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/777467344d4b4b4950565342647635592d78536d646700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,439 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-005] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-20 08:05:51,439 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/3769554a53624e61733865673555613741466e345a4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,439 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 08:05:51,439 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-009] Bazel-remote Probing EIlLHOpxnFurZctHLz-GPg => False 2024-11-20 08:05:51,440 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-010] Bazel-remote Probing 6uVgQqomL2yh52t3Wi-5Og => False 2024-11-20 08:05:51,440 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "HEAD /ac/52696d5031345a792d344c41376f5634665250796c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,441 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 6uVgQqomL2yh52t3Wi-5Og created (provider=None) 2024-11-20 08:05:51,441 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing 0gEZQYRA1wPIy1cG2rLcsA => False 2024-11-20 08:05:51,441 DEBUG (urllib3.connectionpool) [Worker-020] http://cachesrv.internal:8081 "HEAD /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,441 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing wtg4MKKIPVSBdv5Y-xSmdg => False 2024-11-20 08:05:51,442 DEBUG (yalibrary.toolscache) [Worker-005] Tools cache attempt: (request for resource 7336031045) 2024-11-20 08:05:51,442 DEBUG (urllib3.connectionpool) [Worker-021] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:51,442 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing 7iUJSbNas8eg5Ua7AFn4ZA => False 2024-11-20 08:05:51,442 DEBUG (yalibrary.toolscache) [Worker-024] Tools cache attempt: (request for resource 7458630270) 2024-11-20 08:05:51,447 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-018] Bazel-remote Probing RimP14Zy-4LA7oV4fRPylw => False 2024-11-20 08:05:51,447 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 7iUJSbNas8eg5Ua7AFn4ZA created (provider=None) 2024-11-20 08:05:51,450 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-020] Bazel-remote Probing iTMgoEEhV9D7LHyD5DWuLw => False 2024-11-20 08:05:51,451 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-021] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => False 2024-11-20 08:05:51,453 DEBUG (yalibrary.toolscache) [Worker-005] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:05:51,455 DEBUG (yalibrary.toolscache) [Worker-024] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:05:51,455 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for EIlLHOpxnFurZctHLz-GPg created (provider=None) 2024-11-20 08:05:51,458 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 08:05:51,458 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for wtg4MKKIPVSBdv5Y-xSmdg created (provider=None) 2024-11-20 08:05:51,458 DEBUG (yalibrary.fetcher.cache_helper) [Worker-024] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 08:05:51,458 DEBUG (library.python.filelock) [Worker-005] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 08:05:51,458 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for RimP14Zy-4LA7oV4fRPylw created (provider=None) 2024-11-20 08:05:51,459 DEBUG (library.python.filelock) [Worker-024] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 08:05:51,459 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 08:05:51,459 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,459 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for iTMgoEEhV9D7LHyD5DWuLw created (provider=None) 2024-11-20 08:05:51,459 DEBUG (yalibrary.fetcher.cache_helper) [Worker-024] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 08:05:51,460 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 08:05:51,460 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for kaKhVKkwv1kkbgok_0Mn2A created (provider=None) 2024-11-20 08:05:51,460 DEBUG (exts.http_client) [Worker-005] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.w7hsx9zz.xe0kovj8.part, expect md5- 2024-11-20 08:05:51,460 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,461 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for uT2MdPM1ydQBOmrnrdd71Q created (provider=None) 2024-11-20 08:05:51,462 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 08:05:51,462 DEBUG (exts.http_client) [Worker-024] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.d6osoecr.ycj3kl5l.part, expect md5- 2024-11-20 08:05:51,463 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for ze-6pSAEw6cTD7hckEkFBw created (provider=None) 2024-11-20 08:05:51,470 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-20 08:05:51,470 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:51,476 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-20 08:05:51,477 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-20 08:05:51,478 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-018] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 08:05:51,481 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-20 08:05:51,483 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-20 08:05:51,485 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-20 08:05:51,486 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-20 08:05:51,490 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-20 08:05:51,492 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-20 08:05:51,493 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-20 08:05:51,496 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-20 08:05:51,497 DEBUG (yalibrary.toolscache) [Worker-012] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-20 08:05:51,498 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-20 08:05:51,499 DEBUG (yalibrary.toolscache) [Worker-015] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-20 08:05:51,500 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-20 08:05:51,506 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-20 08:05:51,507 DEBUG (yalibrary.toolscache) [Worker-018] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-20 08:05:51,532 DEBUG (exts.http_client) [Worker-005] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Wed, 20 Nov 2024 08:05:51 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: a2530839eb7ea45a 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=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 08:05:51,624 DEBUG (exts.http_client) [Worker-005] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.w7hsx9zz, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.162820 2024-11-20 08:05:51,624 DEBUG (yalibrary.fetcher.common) [Worker-005] extract /home/runner/.ya/tools/v4/7336031045/resource.w7hsx9zz to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-20 08:05:51,787 DEBUG (exts.http_client) [Worker-024] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Wed, 20 Nov 2024 08:05:51 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: 1a97cac6c51b592f 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=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 08:05:51,790 DEBUG (library.python.filelock) [Worker-005] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 08:05:51,793 DEBUG (yalibrary.toolscache) [Worker-005] AC cache attempt: (get uid c05d1cc35b13d68ff2813d471b51e6a1) 2024-11-20 08:05:51,797 DEBUG (yalibrary.toolscache) [Worker-068] AC cache attempt: (get uid ba3616f3c2b9efd716e81e495e49e327) 2024-11-20 08:05:51,804 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/f9e0786c251e14ea2f20b738a46b1f36809563828dea2f60b28d4ae895bf0e11 HTTP/1.1" 404 0 2024-11-20 08:05:51,804 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing c05d1cc35b13d68ff2813d471b51e6a1 => False 2024-11-20 08:05:51,804 DEBUG (root) [Worker-005] Run node 0gEZQYRA1wPIy1cG2rLcsA in build root /home/runner/.ya/build/build_root/pfsr/000011 2024-11-20 08:05:51,805 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "HEAD /ac/a97f7306190b1c1b60f5e8f85fce18aaf5e092420771c9dcdb810af290aac911 HTTP/1.1" 404 0 2024-11-20 08:05:51,806 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Bazel-remote Probing ba3616f3c2b9efd716e81e495e49e327 => False 2024-11-20 08:05:51,806 DEBUG (root) [Worker-068] Run node 6uVgQqomL2yh52t3Wi-5Og in build root /home/runner/.ya/build/build_root/pfsr/000010 2024-11-20 08:05:52,167 DEBUG (yalibrary.toolscache) [Worker-072] AC cache attempt: (put uid 0gEZQYRA1wPIy1cG2rLcsA) 2024-11-20 08:05:52,168 DEBUG (yalibrary.runner.tasks.result) [Worker-005] Result node 0gEZQYRA1wPIy1cG2rLcsA processed successfully: True 2024-11-20 08:05:52,169 DEBUG (yalibrary.toolscache) [Worker-072] AC cache attempt: (put uid c05d1cc35b13d68ff2813d471b51e6a1) 2024-11-20 08:05:52,173 DEBUG (urllib3.connectionpool) [Worker-075] http://cachesrv.internal:8081 "HEAD /ac/3067455a51595241317750497931634732724c63734100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:52,173 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-075] Bazel-remote Probing 0gEZQYRA1wPIy1cG2rLcsA => False 2024-11-20 08:05:52,174 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-075] Put ydb/docs/ru/core/docs-ru-core.docslib(0gEZQYRA1wPIy1cG2rLcsA) to Bazel-remote 2024-11-20 08:05:52,183 DEBUG (urllib3.connectionpool) [Worker-075] http://cachesrv.internal:8081 "PUT /cas/79ef3f1a8907a136d67684fb0845e1f79f0fe21c632ae0a099008a62082c5f9f HTTP/1.1" 200 0 2024-11-20 08:05:52,193 DEBUG (urllib3.connectionpool) [Worker-075] http://cachesrv.internal:8081 "PUT /cas/0e158f458aa7db019b05fb294a431fcab7fff8db41e8b3de5ae4b263356ea21c HTTP/1.1" 200 0 2024-11-20 08:05:52,390 DEBUG (yalibrary.runner.tasks.result) [Worker-084] Result node 6uVgQqomL2yh52t3Wi-5Og processed successfully: True 2024-11-20 08:05:52,390 DEBUG (yalibrary.toolscache) [Worker-088] AC cache attempt: (put uid 6uVgQqomL2yh52t3Wi-5Og) 2024-11-20 08:05:52,392 DEBUG (yalibrary.toolscache) [Worker-088] AC cache attempt: (put uid ba3616f3c2b9efd716e81e495e49e327) 2024-11-20 08:05:52,396 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "HEAD /ac/3675566751716f6d4c3279683532743357692d354f6700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:05:52,396 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Bazel-remote Probing 6uVgQqomL2yh52t3Wi-5Og => False 2024-11-20 08:05:52,397 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Put ydb/docs/__docs_internal.docslib.fake(6uVgQqomL2yh52t3Wi-5Og) to Bazel-remote 2024-11-20 08:05:52,406 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/dbf66a12212149bb7ff858d8a15eb6125acce692a22dd25736f822561d4ec8c5 HTTP/1.1" 200 0 2024-11-20 08:05:52,417 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/bfcf666365fcb256780750614f69ffa5d33ae872173427dd38e475ecf34260a6 HTTP/1.1" 200 0 2024-11-20 08:05:52,612 DEBUG (urllib3.connectionpool) [Worker-075] http://cachesrv.internal:8081 "PUT /cas/ef5472228571c3e1f0d7cbf74a39095686e4e04a7c7de1470d38a141245f6584 HTTP/1.1" 200 0 2024-11-20 08:05:52,623 DEBUG (urllib3.connectionpool) [Worker-075] http://cachesrv.internal:8081 "PUT /ac/3067455a51595241317750497931634732724c63734100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:52,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-075] Put ydb/docs/ru/core/docs-ru-core.docslib(0gEZQYRA1wPIy1cG2rLcsA) size=29634833 to Bazel-remote completed 2024-11-20 08:05:53,008 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /cas/ecda1c0ef84c3ec854e7a3d2f81ae515cd51c4f27d06e5353d207494d1a99462 HTTP/1.1" 200 0 2024-11-20 08:05:53,016 DEBUG (urllib3.connectionpool) [Worker-068] http://cachesrv.internal:8081 "PUT /ac/3675566751716f6d4c3279683532743357692d354f6700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:05:53,017 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-068] Put ydb/docs/__docs_internal.docslib.fake(6uVgQqomL2yh52t3Wi-5Og) size=51589393 to Bazel-remote completed 2024-11-20 08:06:00,111 DEBUG (exts.http_client) [Worker-024] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.d6osoecr, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=8.640794 2024-11-20 08:06:00,111 DEBUG (yalibrary.fetcher.common) [Worker-024] extract /home/runner/.ya/tools/v4/7458630270/resource.d6osoecr to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-20 08:06:00,702 DEBUG (library.python.filelock) [Worker-024] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 08:06:00,704 DEBUG (yalibrary.toolscache) [Worker-024] AC cache attempt: (get uid 3290ff333bcf8dd4e904e34543e6fa26) 2024-11-20 08:06:00,704 DEBUG (yalibrary.toolscache) [Worker-100] AC cache attempt: (get uid 3160556c045a2b1955ca611f6084d3f6) 2024-11-20 08:06:00,704 DEBUG (yalibrary.toolscache) [Worker-098] AC cache attempt: (get uid 56c1230e6fa415d3646d74ea4611b075) 2024-11-20 08:06:00,706 DEBUG (yalibrary.toolscache) [Worker-099] AC cache attempt: (get uid 334169897d0dd952607658dc834812c6) 2024-11-20 08:06:00,711 DEBUG (urllib3.connectionpool) [Worker-024] http://cachesrv.internal:8081 "HEAD /ac/7b3a1f2d042dbc5fecffd37fbfdd6d0b3b27c86da106f288d0a1e1f11db19411 HTTP/1.1" 404 0 2024-11-20 08:06:00,712 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-024] Bazel-remote Probing 3290ff333bcf8dd4e904e34543e6fa26 => False 2024-11-20 08:06:00,712 DEBUG (root) [Worker-024] Run node wtg4MKKIPVSBdv5Y-xSmdg in build root /home/runner/.ya/build/build_root/pfsr/000012 2024-11-20 08:06:00,713 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/e88f8ae883020b8562d8b4cd492a9da49e96577ad2299151f2b19a6135ba5711 HTTP/1.1" 404 0 2024-11-20 08:06:00,713 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing 3160556c045a2b1955ca611f6084d3f6 => False 2024-11-20 08:06:00,714 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/7abaf07446cc5d5c6f74ce6f5193fb37ddfbff7258c44485d22ee03052b57a11 HTTP/1.1" 404 0 2024-11-20 08:06:00,715 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "HEAD /ac/effbed5809ab2c4aa1e3368ff78c890d4d474c8c429d5131947d85747383ed11 HTTP/1.1" 404 0 2024-11-20 08:06:00,715 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing 334169897d0dd952607658dc834812c6 => False 2024-11-20 08:06:00,715 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Bazel-remote Probing 56c1230e6fa415d3646d74ea4611b075 => False 2024-11-20 08:06:00,718 DEBUG (root) [Worker-100] Run node 7iUJSbNas8eg5Ua7AFn4ZA in build root /home/runner/.ya/build/build_root/pfsr/000014 2024-11-20 08:06:00,750 DEBUG (root) [Worker-099] Run node RimP14Zy-4LA7oV4fRPylw in build root /home/runner/.ya/build/build_root/pfsr/000016 2024-11-20 08:06:00,768 DEBUG (root) [Worker-098] Run node iTMgoEEhV9D7LHyD5DWuLw in build root /home/runner/.ya/build/build_root/pfsr/000017 2024-11-20 08:06:04,409 DEBUG (yalibrary.toolscache) [Worker-105] AC cache attempt: (put uid wtg4MKKIPVSBdv5Y-xSmdg) 2024-11-20 08:06:04,410 DEBUG (yalibrary.toolscache) [Worker-107] AC cache attempt: (get uid a1b250869fc4e0857f9495bf80a9b11c) 2024-11-20 08:06:04,410 DEBUG (yalibrary.runner.tasks.result) [Worker-024] Result node wtg4MKKIPVSBdv5Y-xSmdg processed successfully: True 2024-11-20 08:06:04,411 DEBUG (yalibrary.toolscache) [Worker-105] AC cache attempt: (put uid 3290ff333bcf8dd4e904e34543e6fa26) 2024-11-20 08:06:04,414 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "HEAD /ac/777467344d4b4b4950565342647635592d78536d646700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:04,415 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Bazel-remote Probing wtg4MKKIPVSBdv5Y-xSmdg => False 2024-11-20 08:06:04,415 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(wtg4MKKIPVSBdv5Y-xSmdg) to Bazel-remote 2024-11-20 08:06:04,418 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "HEAD /ac/958149544326cb56e5a8392664758652f3c125f39a72e855d24c4a2a20182c11 HTTP/1.1" 404 0 2024-11-20 08:06:04,418 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-107] Bazel-remote Probing a1b250869fc4e0857f9495bf80a9b11c => False 2024-11-20 08:06:04,418 DEBUG (root) [Worker-107] Run node EIlLHOpxnFurZctHLz-GPg in build root /home/runner/.ya/build/build_root/pfsr/00000f 2024-11-20 08:06:04,424 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/879ef2d36b306bf8d8a7ee59bcb13cce77df3236424db2e8ca6a9b20c4353e72 HTTP/1.1" 200 0 2024-11-20 08:06:04,450 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/218a4cb8720bdba4718f7048d99332dd6a2a65f05f8d600f1b0224828ac33de4 HTTP/1.1" 200 0 2024-11-20 08:06:04,468 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid 7iUJSbNas8eg5Ua7AFn4ZA) 2024-11-20 08:06:04,469 DEBUG (yalibrary.toolscache) [Worker-119] AC cache attempt: (get uid 4bc50fed99ff1c1fe2cd0eb950b430d7) 2024-11-20 08:06:04,469 DEBUG (yalibrary.runner.tasks.result) [Worker-120] Result node 7iUJSbNas8eg5Ua7AFn4ZA processed successfully: True 2024-11-20 08:06:04,470 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid 3160556c045a2b1955ca611f6084d3f6) 2024-11-20 08:06:04,473 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "HEAD /ac/3769554a53624e61733865673555613741466e345a4100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:04,473 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Bazel-remote Probing 7iUJSbNas8eg5Ua7AFn4ZA => False 2024-11-20 08:06:04,473 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(7iUJSbNas8eg5Ua7AFn4ZA) to Bazel-remote 2024-11-20 08:06:04,477 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "HEAD /ac/136192c532116d68235b420e7336912e1331b8536fba307f65a30af0ae5c1811 HTTP/1.1" 404 0 2024-11-20 08:06:04,477 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Bazel-remote Probing 4bc50fed99ff1c1fe2cd0eb950b430d7 => False 2024-11-20 08:06:04,477 DEBUG (root) [Worker-119] Run node ze-6pSAEw6cTD7hckEkFBw in build root /home/runner/.ya/build/build_root/pfsr/00000e 2024-11-20 08:06:04,483 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/9773ca542e4bf1126aaab1c522a2499b2ac324ddc5c156228c0b3ba805052f89 HTTP/1.1" 200 0 2024-11-20 08:06:04,492 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/287ab994887fa7cac44b34b28c65c3fb7d4ed6a66cccc6ac56dc2e904ec660f5 HTTP/1.1" 200 0 2024-11-20 08:06:04,506 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/38973e313f9b8863dca9d68a013b22aa9af0e3c771d6b18abd6072f885afd0d8 HTTP/1.1" 200 0 2024-11-20 08:06:04,515 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /ac/777467344d4b4b4950565342647635592d78536d646700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:04,515 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(wtg4MKKIPVSBdv5Y-xSmdg) size=1092285 to Bazel-remote completed 2024-11-20 08:06:04,531 DEBUG (yalibrary.toolscache) [Worker-134] AC cache attempt: (put uid RimP14Zy-4LA7oV4fRPylw) 2024-11-20 08:06:04,532 DEBUG (yalibrary.toolscache) [Worker-135] AC cache attempt: (get uid 30991400ab9de4e3fe17608b0ffaa58d) 2024-11-20 08:06:04,533 DEBUG (yalibrary.toolscache) [Worker-134] AC cache attempt: (put uid 334169897d0dd952607658dc834812c6) 2024-11-20 08:06:04,534 DEBUG (yalibrary.runner.tasks.result) [Worker-133] Result node RimP14Zy-4LA7oV4fRPylw processed successfully: True 2024-11-20 08:06:04,542 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/52696d5031345a792d344c41376f5634665250796c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:04,542 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing RimP14Zy-4LA7oV4fRPylw => False 2024-11-20 08:06:04,543 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "HEAD /ac/5fc7c3498f56da9f49463661f3833c0501f9aafb208a5ec383d7220b66dbca11 HTTP/1.1" 404 0 2024-11-20 08:06:04,543 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Bazel-remote Probing 30991400ab9de4e3fe17608b0ffaa58d => False 2024-11-20 08:06:04,544 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(RimP14Zy-4LA7oV4fRPylw) to Bazel-remote 2024-11-20 08:06:04,545 DEBUG (root) [Worker-135] Run node uT2MdPM1ydQBOmrnrdd71Q in build root /home/runner/.ya/build/build_root/pfsr/00000d 2024-11-20 08:06:04,545 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/046e10622201c26693c4e1b08bcac6ee3f92685f516a992904ea3fbc2fe9c41b HTTP/1.1" 200 0 2024-11-20 08:06:04,553 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/12042b32befcb8f6682731d5ec43fc6541468fac3cac59909e0d2222a38464a5 HTTP/1.1" 200 0 2024-11-20 08:06:04,555 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /ac/3769554a53624e61733865673555613741466e345a4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:04,555 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(7iUJSbNas8eg5Ua7AFn4ZA) size=1092256 to Bazel-remote completed 2024-11-20 08:06:04,562 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/d45525899a68a92f8ee916e205bc5498bd43ed6721431a527eb6cc7a2fb2b795 HTTP/1.1" 200 0 2024-11-20 08:06:04,616 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/e2ccf2f07d9d33cd43500c010ca00fe916ec24ef495a1596245861fc8a7774c6 HTTP/1.1" 200 0 2024-11-20 08:06:04,626 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /ac/52696d5031345a792d344c41376f5634665250796c7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:04,626 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(RimP14Zy-4LA7oV4fRPylw) size=1092281 to Bazel-remote completed 2024-11-20 08:06:09,561 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid EIlLHOpxnFurZctHLz-GPg) 2024-11-20 08:06:09,563 DEBUG (yalibrary.runner.tasks.result) [Worker-017] Result node EIlLHOpxnFurZctHLz-GPg processed successfully: True 2024-11-20 08:06:09,563 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid a1b250869fc4e0857f9495bf80a9b11c) 2024-11-20 08:06:09,567 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "HEAD /ac/45496c4c484f70786e4675725a6374484c7a2d47506700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:09,567 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-107] Bazel-remote Probing EIlLHOpxnFurZctHLz-GPg => False 2024-11-20 08:06:09,567 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-107] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(EIlLHOpxnFurZctHLz-GPg) to Bazel-remote 2024-11-20 08:06:09,577 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "PUT /cas/06f8bf36afe7a71fc2fa60f57f7ec9d8ca3a75fcd76dc825b109e95950b5dd10 HTTP/1.1" 200 0 2024-11-20 08:06:09,585 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "PUT /cas/12af11f47aa00f42c9a2f4fa12e8194bcfceb1d5c7769eb7a0bb8b5a200d394f HTTP/1.1" 200 0 2024-11-20 08:06:09,654 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "PUT /cas/ed22720edffbe22b283d5b0b1725f0983c57e497ef28813c81c9eb4ac51f5e25 HTTP/1.1" 200 0 2024-11-20 08:06:09,662 DEBUG (urllib3.connectionpool) [Worker-107] http://cachesrv.internal:8081 "PUT /ac/45496c4c484f70786e4675725a6374484c7a2d47506700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:09,663 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-107] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(EIlLHOpxnFurZctHLz-GPg) size=2779917 to Bazel-remote completed 2024-11-20 08:06:10,520 DEBUG (yalibrary.toolscache) [Worker-029] AC cache attempt: (put uid ze-6pSAEw6cTD7hckEkFBw) 2024-11-20 08:06:10,521 DEBUG (yalibrary.runner.tasks.result) [Worker-030] Result node ze-6pSAEw6cTD7hckEkFBw processed successfully: True 2024-11-20 08:06:10,522 DEBUG (yalibrary.toolscache) [Worker-029] AC cache attempt: (put uid 4bc50fed99ff1c1fe2cd0eb950b430d7) 2024-11-20 08:06:10,525 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "HEAD /ac/7a652d367053414577366354443768636b456b46427700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:10,526 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Bazel-remote Probing ze-6pSAEw6cTD7hckEkFBw => False 2024-11-20 08:06:10,526 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(ze-6pSAEw6cTD7hckEkFBw) to Bazel-remote 2024-11-20 08:06:10,536 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/c6988210fbb8e43c3be08ab798de68b915f102a736d9500c39ecce20c8c9d5cd HTTP/1.1" 200 0 2024-11-20 08:06:10,546 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/23aa7d9435faa63a979493abb78c928bde1701b60079edbf1ac737f19ddd78f3 HTTP/1.1" 200 0 2024-11-20 08:06:10,606 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /cas/29ad34d2ef829353caacd107fc5f2406731c00a029e4599f24468c1ed600f34d HTTP/1.1" 200 0 2024-11-20 08:06:10,614 DEBUG (urllib3.connectionpool) [Worker-119] http://cachesrv.internal:8081 "PUT /ac/7a652d367053414577366354443768636b456b46427700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:10,614 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-119] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(ze-6pSAEw6cTD7hckEkFBw) size=2779912 to Bazel-remote completed 2024-11-20 08:06:10,652 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (put uid uT2MdPM1ydQBOmrnrdd71Q) 2024-11-20 08:06:10,653 DEBUG (yalibrary.runner.tasks.result) [Worker-038] Result node uT2MdPM1ydQBOmrnrdd71Q processed successfully: True 2024-11-20 08:06:10,653 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (put uid 30991400ab9de4e3fe17608b0ffaa58d) 2024-11-20 08:06:10,657 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "HEAD /ac/7554324d64504d31796451424f6d726e72646437315100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:10,657 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Bazel-remote Probing uT2MdPM1ydQBOmrnrdd71Q => False 2024-11-20 08:06:10,658 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(uT2MdPM1ydQBOmrnrdd71Q) to Bazel-remote 2024-11-20 08:06:10,667 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/cdddcdf043997717b660eed9ecd4bac19a11ea6980091154dc2107a19983af18 HTTP/1.1" 200 0 2024-11-20 08:06:10,676 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/4dd789d2350a5568d2c8fec530b50d20795e3530ce342655e22ee889a4f5faf5 HTTP/1.1" 200 0 2024-11-20 08:06:10,712 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/b8d043fb92d0eb7663b1d34db5ad7d8a322a51d8f2f5ab8577d4ce2ce56a5836 HTTP/1.1" 200 0 2024-11-20 08:06:10,721 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /ac/7554324d64504d31796451424f6d726e72646437315100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:10,721 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(uT2MdPM1ydQBOmrnrdd71Q) size=2779881 to Bazel-remote completed 2024-11-20 08:06:21,971 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid iTMgoEEhV9D7LHyD5DWuLw) 2024-11-20 08:06:21,971 DEBUG (yalibrary.toolscache) [Worker-054] AC cache attempt: (get uid 2d7962fb2639527e6297eba2caf0e562) 2024-11-20 08:06:21,972 DEBUG (yalibrary.runner.tasks.result) [Worker-053] Result node iTMgoEEhV9D7LHyD5DWuLw processed successfully: True 2024-11-20 08:06:21,973 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid 56c1230e6fa415d3646d74ea4611b075) 2024-11-20 08:06:21,976 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "HEAD /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:21,976 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Bazel-remote Probing iTMgoEEhV9D7LHyD5DWuLw => False 2024-11-20 08:06:21,976 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Put ydb/docs/preprocessed.tar.gz(iTMgoEEhV9D7LHyD5DWuLw) to Bazel-remote 2024-11-20 08:06:21,979 DEBUG (urllib3.connectionpool) [Worker-054] http://cachesrv.internal:8081 "HEAD /ac/a38b66c50e209cbbfa54a69756cfffa9f1c9f2540432da07020a0a54a2a18711 HTTP/1.1" 404 0 2024-11-20 08:06:21,979 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-054] Bazel-remote Probing 2d7962fb2639527e6297eba2caf0e562 => False 2024-11-20 08:06:21,980 DEBUG (root) [Worker-054] Run node kaKhVKkwv1kkbgok_0Mn2A in build root /home/runner/.ya/build/build_root/pfsr/000019 2024-11-20 08:06:21,986 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/04a8963e17cb10a6dd4ffee67f52ed75a072f95a9a80c059060e112d2d19173d HTTP/1.1" 200 0 2024-11-20 08:06:21,996 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/7cec2c62ccbad1fb7f3e3317002e11a4f7c7763895544c064603dc763e812a86 HTTP/1.1" 200 0 2024-11-20 08:06:22,482 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /cas/e7fbc2bccde2f0e9002e41eb33d99f3354c8492c5599f509028ba20c9d4a5b70 HTTP/1.1" 200 0 2024-11-20 08:06:22,491 DEBUG (urllib3.connectionpool) [Worker-098] http://cachesrv.internal:8081 "PUT /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:22,491 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-098] Put ydb/docs/preprocessed.tar.gz(iTMgoEEhV9D7LHyD5DWuLw) size=36209522 to Bazel-remote completed 2024-11-20 08:07:08,699 DEBUG (yalibrary.toolscache) [Worker-060] AC cache attempt: (put uid kaKhVKkwv1kkbgok_0Mn2A) 2024-11-20 08:07:08,699 DEBUG (yalibrary.runner.tasks.result) [Worker-054] Result node kaKhVKkwv1kkbgok_0Mn2A processed successfully: True 2024-11-20 08:07:08,700 DEBUG (yalibrary.toolscache) [Worker-060] AC cache attempt: (put uid 2d7962fb2639527e6297eba2caf0e562) 2024-11-20 08:07:08,704 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:07:08,705 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => False 2024-11-20 08:07:08,705 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Put ydb/docs/ydb-docs.tar.gz(kaKhVKkwv1kkbgok_0Mn2A) to Bazel-remote 2024-11-20 08:07:08,714 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/b0ab2b8996f61c22222931d8f69d449b5982f32eef7a1a7c5a758b30c7ef7277 HTTP/1.1" 200 0 2024-11-20 08:07:08,722 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/ccbe6e03abd0774252acdc439808a3cfc169d0dc9cbfdae3ae39738524849d66 HTTP/1.1" 200 0 2024-11-20 08:07:09,125 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/b54bd437e8fbf5c9042aa40377ce048a486f9794263505a73e3428d33132f9fa HTTP/1.1" 200 0 2024-11-20 08:07:09,134 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:07:09,134 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Put ydb/docs/ydb-docs.tar.gz(kaKhVKkwv1kkbgok_0Mn2A) size=39679440 to Bazel-remote completed 2024-11-20 08:07:09,195 DEBUG (yalibrary.runner.build_root) [Worker-073] Build root /home/runner/.ya/build/build_root/pfsr created=25 left=12 2024-11-20 08:07:09,196 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/pfsr/STAMP 2024-11-20 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(wtg4MKKIPVSBdv5Y-xSmdg$(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 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(7iUJSbNas8eg5Ua7AFn4ZA$(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 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(RimP14Zy-4LA7oV4fRPylw$(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 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(EIlLHOpxnFurZctHLz-GPg$(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 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(ze-6pSAEw6cTD7hckEkFBw$(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 08:07:09,207 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(uT2MdPM1ydQBOmrnrdd71Q$(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 08:07:09,208 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(iTMgoEEhV9D7LHyD5DWuLw$(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 08:07:09,208 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(kaKhVKkwv1kkbgok_0Mn2A$(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 08:07:09,208 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-20 08:07:09,208 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.004741907119751,"qty":1},"prepare_all_nodes":{"sum":0.058734893798828,"qty":1},"compact_cache":{"sum":0.005095958709717,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.34477162361145,"qty":13},"prepare[DO]":{"sum":0.003427505493164,"qty":3},"put_in_cache[DO]":{"sum":0.038288354873657,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.352139234542847,"qty":1},"DO":{"sum":97.575216293334961,"qty":10},"result[DO]":{"sum":0.020602226257324,"qty":10},"write_through_caches[DO]":{"sum":0.077659368515015,"qty":10},"put_in_dist_cache[DO]":{"sum":2.830740928649902,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":9.263168573379517,"qty":1},"clean_build_root":{"sum":0.000210523605347,"qty":1}},"all":{"qty":75,"sum":110.57479739189148},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732089951.439396142959595,1732089960.702564716339111],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":77.789492607116699,"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 08:07:09,208 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-20 08:07:09,208 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732090029.2086802 2024-11-20 08:07:09,208 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732090029.2086802 2024-11-20 08:07:09,208 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732090029.2086802 2024-11-20 08:07:09,209 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732090029.2090948 2024-11-20 08:07:09,209 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732090029.2090948 2024-11-20 08:07:09,209 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732090029.2090948 2024-11-20 08:07:09,224 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-20 08:07:09,224 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-20 08:07:09,224 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,224 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,224 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-20 08:07:09,224 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-20 08:07:09,225 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-20 08:07:09,227 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-20 08:07:09,234 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,234 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,235 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,235 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:09,235 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732090029.2356255 2024-11-20 08:07:09,235 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732090029.2356255 2024-11-20 08:07:09,235 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732090029.2356255 2024-11-20 08:07:09,236 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-20 08:07:09,236 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-20 08:07:09,236 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-20 08:07:09,237 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168760440 2024-11-20 08:07:09,237 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732090029.237504 2024-11-20 08:07:09,237 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732090029.237504 2024-11-20 08:07:09,237 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732090029.237504 2024-11-20 08:07:09,238 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-20 08:07:09,238 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-20 08:07:09,238 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 08:07:09,238 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-20 08:07:09,238 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",573,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",21180,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",46695,"local"]] 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 76871 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 68448 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 68448 2024-11-20 08:07:09,239 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-20 08:07:09,240 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-20 08:07:09,240 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 46695 2024-11-20 08:07:09,241 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-20 08:07:09,241 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-20 08:07:09,241 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-20 08:07:09,241 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-20 08:07:09,241 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 97286 2024-11-20 08:07:09,242 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732090029.2422135 2024-11-20 08:07:09,242 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732090029.2422135 2024-11-20 08:07:09,242 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732090029.2422135 2024-11-20 08:07:09,242 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732090029.2428854 2024-11-20 08:07:09,242 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732090029.2428854 2024-11-20 08:07:09,243 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732090029.2428854 2024-11-20 08:07:09,243 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 08:07:09,243 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-20 08:07:09,243 DEBUG (StoredReport) [MainThread] Finish report 2024-11-20 08:07:09,243 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 9.5367431640625e-05}, 'BuildResultsListener': {'duration_sec': 0.0014405250549316406}} 2024-11-20 08:07:09,244 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-20 08:07:09,244 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 08:07:09,244 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 08:07:09,244 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:07:09,244 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:07:09,244 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:07:09,244 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:07:09,244 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:07:09,244 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:07:09,245 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-20 08:07:09,245 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-20 08:07:09,245 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 08:07:09,245 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732090029.2459123 2024-11-20 08:07:09,245 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732090029.2459123 2024-11-20 08:07:09,246 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732090029.2459123 2024-11-20 08:07:09,246 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-20 08:07:09,247 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732090029.2470367 2024-11-20 08:07:09,247 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732090029.2470367 2024-11-20 08:07:09,247 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732090029.2470367 2024-11-20 08:07:09,247 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732090029.2472444 2024-11-20 08:07:09,247 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732090029.2472444 2024-11-20 08:07:09,247 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732090029.2472444 2024-11-20 08:07:09,247 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732090029.2479007 2024-11-20 08:07:09,247 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732090029.2479007 2024-11-20 08:07:09,248 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732090029.2479007 2024-11-20 08:07:09,248 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732090029.2481005 2024-11-20 08:07:09,248 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732090029.2481005 2024-11-20 08:07:09,248 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732090029.2481005 2024-11-20 08:07:09,248 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732090029.248295 2024-11-20 08:07:09,248 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732090029.248295 2024-11-20 08:07:09,248 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732090029.248295 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000089 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000027 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000003 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000002 2024-11-20 08:07:09,248 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000016 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000009 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000002 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000004 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000008 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000015 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000007 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000003 2024-11-20 08:07:09,249 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000001 2024-11-20 08:07:09,249 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 08:07:09,249 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732089949.2938.p2vu6yj0 2024-11-20 08:07:09,250 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-20 08:07:09,250 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000615 2024-11-20 08:07:09,250 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-20 08:07:09,250 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000005 2024-11-20 08:07:09,250 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000006 2024-11-20 08:07:09,250 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000008 2024-11-20 08:07:09,251 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001074 2024-11-20 08:07:09,251 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000007 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000005 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000006 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000008 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000005 2024-11-20 08:07:09,252 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])