2024-11-19 12:49:30,856 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000018 2024-11-19 12:49:30,857 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-19 12:49:30,857 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000926 2024-11-19 12:49:30,858 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:49:30,858 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:30,858 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:30,858 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:49:30,858 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000242 2024-11-19 12:49:30,858 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,858 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000488 2024-11-19 12:49:30,858 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-19 12:49:30,858 DEBUG (root) [MainThread] Start up timestamp 2024-11-19T12:49:30.UTC (2024-11-19T12:49:30.UTC) 2024-11-19 12:49:30,858 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000046 2024-11-19 12:49:30,859 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000006 2024-11-19 12:49:30,859 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000454 2024-11-19 12:49:30,859 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732020570.2954.umwo0a6i instead of None 2024-11-19 12:49:30,859 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000155 2024-11-19 12:49:30,859 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732020570.8597753 2024-11-19 12:49:30,859 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732020570.8597753 2024-11-19 12:49:30,859 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732020570.8597753 2024-11-19 12:49:30,860 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732020570.8603604 2024-11-19 12:49:30,860 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732020570.8603604 2024-11-19 12:49:30,860 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732020570.8603604 2024-11-19 12:49:30,860 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-19 12:49:30,873 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-19 12:49:30,873 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-19 12:49:30,873 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-19 12:49:30,873 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:49:30,874 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-19 12:49:30,874 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-19 12:49:30,874 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-19 12:49:30,875 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-19 12:49:30,875 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf` with `9` keys 2024-11-19 12:49:30,875 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:49:30,875 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-19 12:49:30,886 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-19 12:49:30,886 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-19 12:49:30,887 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732020570.8879213 2024-11-19 12:49:30,887 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732020570.8879213 2024-11-19 12:49:30,888 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732020570.8879213 2024-11-19 12:49:30,888 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732020570.8880649 2024-11-19 12:49:30,888 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732020570.8880649 2024-11-19 12:49:30,888 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732020570.8880649 2024-11-19 12:49:30,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Add [('params', ), ('hide_token', ), ('state', ), ('display', ), ('custom_file_log', ), ('display_log', ), ('vcs_type', ), ('self_info', ), ('fetcher_params', ), ('use_universal_fetcher_everywhere', ), ('docker_config_path', ), ('hide_token2', ), ('fetchers_storage', ), ('fetcher', ), ('showstack', ), ('profile', ), ('mlockall', ), ('event_queue', ), ('changelist_store', ), ('evlog', ), ('dump_debug', )] to ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir', 'params', 'hide_token', 'state', 'display']) 2024-11-19 12:49:30,888 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:30,888 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-19 12:49:30,888 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-19 12:49:30,888 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000316 2024-11-19 12:49:30,890 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,890 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:30,890 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:49:30,891 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,891 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:49:30,891 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,891 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002397 2024-11-19 12:49:30,891 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000262 2024-11-19 12:49:30,891 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000057 2024-11-19 12:49:30,892 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,892 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000995 2024-11-19 12:49:30,892 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000046 2024-11-19 12:49:30,893 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:49:30,893 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:30,893 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:30,893 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:49:30,893 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000247 2024-11-19 12:49:30,893 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-19 12:49:30,893 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-19 12:49:30,893 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000106 2024-11-19 12:49:30,893 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-19 12:49:30,893 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000082 2024-11-19 12:49:30,893 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-19 12:49:30,893 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000002 2024-11-19 12:49:30,894 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:30,894 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:30,894 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:30,894 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-19 12:49:30,894 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001163 2024-11-19 12:49:30,894 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000086 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000163 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000003 2024-11-19 12:49:30,895 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000040 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-19 12:49:30,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000298 2024-11-19 12:49:30,895 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-19 12:49:30,896 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000494 2024-11-19 12:49:30,938 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.041998 2024-11-19 12:49:30,938 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-19 12:49:30,939 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732020570.939182 2024-11-19 12:49:30,939 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732020570.939182 2024-11-19 12:49:30,939 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732020570.939182 2024-11-19 12:49:30,939 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732020570.9393544 2024-11-19 12:49:30,939 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732020570.9393544 2024-11-19 12:49:30,939 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732020570.9393544 2024-11-19 12:49:31,075 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732020571.0756729 2024-11-19 12:49:31,075 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732020571.0756729 2024-11-19 12:49:31,075 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732020571.0756729 2024-11-19 12:49:31,075 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732020571.0759377 2024-11-19 12:49:31,075 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732020571.0759377 2024-11-19 12:49:31,076 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732020571.0759377 2024-11-19 12:49:31,076 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732020571.076064 2024-11-19 12:49:31,076 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732020571.076064 2024-11-19 12:49:31,076 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732020571.076064 2024-11-19 12:49:31,076 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-19 12:49:31,076 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-19 12:49:31,076 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-19 12:49:31,076 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732020571.0762997 2024-11-19 12:49:31,076 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732020571.0762997 2024-11-19 12:49:31,076 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732020571.0762997 2024-11-19 12:49:31,076 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732020571.0765703 2024-11-19 12:49:31,076 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732020571.0765703 2024-11-19 12:49:31,076 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732020571.0765703 2024-11-19 12:49:31,079 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-19 12:49:31,079 DEBUG (yalibrary.toolscache) [MainThread] Tools cache parameters: tc enabled=True, tc master=True, ac enabled=True, ac master=True, tc_lock_file=/home/runner/.ya/tools/v4/.cache_lock, ac_lock_file=/home/runner/.ya/build/cache/7/.cache_lock, binary=None, ini=None, tc_conf={}, ac_conf={'cas_logging': 'true', 'graph_info': 'true'}, gl_conf={} 2024-11-19 12:49:31,079 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-19 12:49:31,081 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.FBtyPE1ohs' file to obtain bazel remote password 2024-11-19 12:49:31,081 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732020571.0816228 2024-11-19 12:49:31,081 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732020571.0816228 2024-11-19 12:49:31,081 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732020571.0816228 2024-11-19 12:49:31,082 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-19 12:49:31,082 DEBUG (yalibrary.vcs) [Misc-01] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: ['/home/runner/actions_runner/_work/ydb/ydb'] 2024-11-19 12:49:31,082 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:31,083 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-19 12:49:31,083 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:31,084 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-19 12:49:31,086 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-19 12:49:31,088 DEBUG (build.graph) [MainThread] flags: { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CONSISTENT_DEBUG": "yes", "DEBUGINFO_LINES_ONLY": "yes", "DISABLE_FLAKE8_MIGRATIONS": "yes", "OPENSOURCE": "yes", "TESTS_REQUESTED": "yes", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_EAT_MY_DATA": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" } 2024-11-19 12:49:31,088 DEBUG (build.graph) [MainThread] host toolchain: { "bottle_name": "clang18", "build_type": "release", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CLANG_COVERAGE": "no", "CONSISTENT_DEBUG": "yes", "NO_DEBUGINFO": "yes", "OPENSOURCE": "yes", "TIDY": "no", "TOOL_BUILD_MODE": "yes", "TRAVERSE_RECURSE": "no", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "tool_var": "CLANG-1735056821" } 2024-11-19 12:49:31,089 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-19 12:49:31,089 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-19 12:49:31,089 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-19 12:49:31,089 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: try to fetch by {'darwin': {'uri': 'sbr:7448914799'}, 'darwin-arm64': {'uri': 'sbr:7448913101'}, 'linux': {'uri': 'sbr:7448917907'}, 'linux-aarch64': {'uri': 'sbr:7448911598'}, 'win32-clang-cl': {'uri': 'sbr:7448916360'}} for 'linux' 2024-11-19 12:49:31,089 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:49:31,089 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 7448917907 from sbr:7448917907 to /home/runner/.ya/tools/v4/7448917907 dir, post_process=0) 2024-11-19 12:49:31,090 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-19 12:49:31,092 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-19 12:49:31,101 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-19 12:49:31,101 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-19 12:49:31,102 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:49:31,102 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ya-tc/public.resources.json 2024-11-19 12:49:31,102 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: try to fetch by {'darwin': {'uri': 'sbr:6812263131'}, 'darwin-arm64': {'uri': 'sbr:6812261994'}, 'linux': {'uri': 'sbr:6812260917'}, 'linux-aarch64': {'uri': 'sbr:6812259929'}} for 'linux' 2024-11-19 12:49:31,102 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-19 12:49:31,102 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 6812260917 from sbr:6812260917 to /home/runner/.ya/tools/v4/6812260917 dir, post_process=0) 2024-11-19 12:49:31,102 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:49:31,102 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:49:31,102 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:49:31,102 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:49:31,103 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:49:31,103 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters done: id=6812260917, full_path=/home/runner/.ya/tools/v4/6812260917/ya-tc 2024-11-19 12:49:31,106 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2375, 12849, 'unix:/home/runner/.ya/tools/v4/psingECPMU9.tmp') 2024-11-19 12:49:31,112 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:49:31,112 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:49:31,112 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:49:31,113 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.023839235305786133 2024-11-19 12:49:31,113 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.1135824 2024-11-19 12:49:31,114 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_started=1732020571.1135824 2024-11-19 12:49:31,114 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_started - 1732020571.1135824 2024-11-19 12:49:31,114 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.1140218 2024-11-19 12:49:31,114 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started=1732020571.1140218 2024-11-19 12:49:31,114 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started - 1732020571.1140218 2024-11-19 12:49:31,114 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732020571.1141536 2024-11-19 12:49:31,115 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732020571.1141536 2024-11-19 12:49:31,115 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732020571.1141536 2024-11-19 12:49:31,115 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.114827 2024-11-19 12:49:31,115 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started=1732020571.114827 2024-11-19 12:49:31,115 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started - 1732020571.114827 2024-11-19 12:49:31,115 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-19 12:49:31,115 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.114861 2024-11-19 12:49:31,115 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started=1732020571.114861 2024-11-19 12:49:31,115 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started - 1732020571.114861 2024-11-19 12:49:31,116 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-19 12:49:31,116 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732020571.115138 2024-11-19 12:49:31,116 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732020571.115138 2024-11-19 12:49:31,116 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732020571.115138 2024-11-19 12:49:31,117 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --tags HEAD failed with exit code 128 2024-11-19 12:49:31,120 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-19 12:49:31,122 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'a4eb4031d686ca02da0e45cd32fbefac8b4cfdb2', author:b's-sabitova ', summary:b'Merge bbbaf1794947f8b8f81e16c352eed1337a5c4ac5 into 73654270aa388c26ddae0ef5e4efc4bcc8896f82', svn_id:b'' 2024-11-19 12:49:31,123 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-qy4ifjye2y Host information: Linux ghrun-qy4ifjye2y 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-19 12:49:31,123 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"a4eb4031d686ca02da0e45cd32fbefac8b4cfdb2\",\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-19T12:49:31.000000Z\",\n \"BUILD_HOST\": \"ghrun-qy4ifjye2y\",\n \"BUILD_TIMESTAMP\": 1732020571,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: a4eb4031d686ca02da0e45cd32fbefac8b4cfdb2\\n Branch: \\n Author: s-sabitova \\n Summary: Merge bbbaf1794947f8b8f81e16c352eed1337a5c4ac5 into 73654270aa388c26ddae0ef5e4efc4bcc8896f82\\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-qy4ifjye2y\\n Host information: \\n Linux ghrun-qy4ifjye2y 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: a4eb4031d686ca02da0e45cd32fbefac8b4cfdb2\\n Branch: \\n Author: s-sabitova \\n Summary: Merge bbbaf1794947f8b8f81e16c352eed1337a5c4ac5 into 73654270aa388c26ddae0ef5e4efc4bcc8896f82\\n\",\n \"VCS\": \"git\"\n}" 2024-11-19 12:49:31,123 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.04144930839538574 2024-11-19 12:49:31,152 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-19 12:49:31,153 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-19 12:49:31,298 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-19 12:49:31,298 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-19 12:49:31,298 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.2984605 2024-11-19 12:49:31,298 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished=1732020571.2984605 2024-11-19 12:49:31,298 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732020571.2984605 2024-11-19 12:49:31,298 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.2986846 2024-11-19 12:49:31,298 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started=1732020571.2986846 2024-11-19 12:49:31,298 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started - 1732020571.2986846 2024-11-19 12:49:31,298 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 0x7f349cca3240>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/makefiles.dart'} 2024-11-19 12:49:31,299 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-19 12:49:31,299 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-19 12:49:31,299 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:49:31,299 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-19 12:49:31,299 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/1732020570.2954.umwo0a6i/yatmp0kats94a --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/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:49:31,300 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-19 12:49:31,302 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-19 12:49:31,302 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.3028157 2024-11-19 12:49:31,302 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished=1732020571.3028157 2024-11-19 12:49:31,302 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished - 1732020571.3028157 2024-11-19 12:49:31,303 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.303103 2024-11-19 12:49:31,303 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started=1732020571.303103 2024-11-19 12:49:31,303 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started - 1732020571.303103 2024-11-19 12:49:31,303 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 0x7f349d54bec0>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/makefiles.dart'} 2024-11-19 12:49:31,303 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-19 12:49:31,303 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-19 12:49:31,304 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-19 12:49:31,304 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/1732020570.2954.umwo0a6i/yatmpal49gh7f --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/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:49:31,306 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732020571306608,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571306745,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020571306784,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571306794,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020571306798,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732020571307100,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,307 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020571307105,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,310 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732020571310571,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-19 12:49:31,310 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571310689,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,311 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020571310724,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,311 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571310734,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,311 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020571310739,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,311 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732020571311028,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,311 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020571311033,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,482 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020571482124,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,482 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020571482150,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,483 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020571483781,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,483 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732020571483795,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,485 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732020571485202,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,485 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732020571485212,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,487 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020571487096,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,487 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020571487116,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,488 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020571488768,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,489 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732020571488780,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,490 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732020571490173,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,490 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732020571490181,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,617 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732020571616790,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,621 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732020571620935,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,650 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650414,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650438,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650443,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650531,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732020571650536,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650610,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,651 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650615,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650618,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650622,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650626,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650629,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650633,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571650637,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732020571651548,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571651558,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,652 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732020571651565,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732020571651569,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732020571651574,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571651580,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732020571651584,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732020571651589,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-19 12:49:31,653 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732020571651592,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,654 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654233,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,654 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654252,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,654 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654257,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654334,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732020571654339,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654398,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654404,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654408,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654412,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654416,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654420,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,655 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654423,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571654427,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732020571655343,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571655351,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732020571655359,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732020571655363,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732020571655367,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571655373,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732020571655377,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-19 12:49:31,656 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732020571655381,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-19 12:49:31,657 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732020571655385,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.1020596166,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732020571753667,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732020571753694,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571753702,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571753709,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020571753760,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,754 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":3057,"Sub":"","Type":"Debug","_timestamp":1732020571753850,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,754 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020571753854,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09942570414,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732020571754824,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9021617; load time = 11325; loaded MD5 time = 844; Max loaded MD5 time = 5; loaded count = 2174; max load time = 58; mapped size = 40632376; mapped MD5 time = 7035; Max mapped MD5 time = 422; mapped count = 130; map time = 853; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3706; lstat min us = 0; lstat avr us = 1; lstat max us = 31; 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 = 2358; readdir min us = 0; readdir avr us = 0; readdir max us = 42; (opendir + readdir) sum us = 2358; (lstat + opendir + readdir) sum us = 6064;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732020571753889,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732020571754845,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571754853,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2358,"LoadTime":11325,"LoadedCount":2174,"LoadedMD5Time":844,"LoadedSize":9021617,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":6064,"LstatMaxUs":31,"LstatMinUs":0,"LstatSumUs":3706,"MapTime":853,"MappedCount":130,"MappedMD5Time":7035,"MappedSize":40632376,"MaxLoadTime":58,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":422,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":42,"ReaddirMinUs":0,"ReaddirSumUs":2358,"_timestamp":1732020571753902,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-19 12:49:31,755 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571754861,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9021617,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732020571753915,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020571754913,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":11325,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732020571753921,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,756 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":3059,"Sub":"","Type":"Debug","_timestamp":1732020571754999,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732020571753925,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020571755004,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732020571753928,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":853,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732020571753931,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,756 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9021617; load time = 10618; loaded MD5 time = 834; Max loaded MD5 time = 5; loaded count = 2174; max load time = 83; mapped size = 40632376; mapped MD5 time = 6740; Max mapped MD5 time = 405; mapped count = 130; map time = 780; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3101; lstat min us = 0; lstat avr us = 1; lstat max us = 14; 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 = 2173; readdir min us = 0; readdir avr us = 0; readdir max us = 44; (opendir + readdir) sum us = 2173; (lstat + opendir + readdir) sum us = 5274;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732020571755039,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732020571753934,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732020571753937,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2173,"LoadTime":10618,"LoadedCount":2174,"LoadedMD5Time":834,"LoadedSize":9021617,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5274,"LstatMaxUs":14,"LstatMinUs":0,"LstatSumUs":3101,"MapTime":780,"MappedCount":130,"MappedMD5Time":6740,"MappedSize":40632376,"MaxLoadTime":83,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":405,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":44,"ReaddirMinUs":0,"ReaddirSumUs":2173,"_timestamp":1732020571755052,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9021617; load time = 11325; loaded count = 2174; max load time = 58;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732020571753945,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9021617,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732020571755065,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":11325,"LoadedCount":2174,"LoadedSize":9021617,"MaxLoadTime":58,"_timestamp":1732020571753950,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":10618,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732020571755069,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 4; 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":1732020571753963,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,757 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732020571755072,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":4,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732020571753968,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732020571755075,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,758 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":1732020571753981,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":780,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732020571755078,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732020571753987,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732020571755081,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,758 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":1732020571754003,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732020571755084,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,758 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732020571754007,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9021617; load time = 10618; loaded count = 2174; max load time = 83;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732020571755092,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732020571754013,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":10618,"LoadedCount":2174,"LoadedSize":9021617,"MaxLoadTime":83,"_timestamp":1732020571755097,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732020571754016,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 3; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732020571755109,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,759 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":1732020571754023,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732020571755114,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-19 12:49:31,759 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732020571754028,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-19 12:49:31,759 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":1732020571755127,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732020571754031,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732020571755132,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-19 12:49:31,760 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":1732020571754150,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,760 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":1732020571755148,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732020571754156,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732020571755152,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732020571754160,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732020571755157,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,760 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732020571754163,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732020571755161,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732020571754167,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,761 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":1732020571755168,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732020571754169,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732020571755172,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732020571755176,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020571754180,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,761 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":1732020571755293,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,761 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020571754816,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732020571755298,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732020571754819,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732020571755302,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732020571757695,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732020571755306,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020571757699,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732020571755309,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020571757752,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,762 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732020571755312,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,763 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-relwithdebinfo-nopic). Tool targets: set() 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732020571757754,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732020571757757,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732020571757759,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020571755322,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732020571757762,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020571755946,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,763 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732020571757764,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,764 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732020571755949,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,764 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732020571757826,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,764 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732020571758821,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,764 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732020571757829,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,764 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020571758824,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,764 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-relwithdebinfo-pic). Tool targets: set() 2024-11-19 12:49:31,764 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732020571.7646167 2024-11-19 12:49:31,764 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732020571.7646167 2024-11-19 12:49:31,764 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732020571.7646167 2024-11-19 12:49:31,764 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-19 12:49:31,764 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732020571.764972 2024-11-19 12:49:31,765 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732020571.764972 2024-11-19 12:49:31,765 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732020571.764972 2024-11-19 12:49:31,765 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732020571.765197 2024-11-19 12:49:31,765 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":1732020571758841,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,765 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732020571.765197 2024-11-19 12:49:31,765 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732020571.765197 2024-11-19 12:49:31,765 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732020571.765662 2024-11-19 12:49:31,765 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732020571.765662 2024-11-19 12:49:31,765 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732020571.765662 2024-11-19 12:49:31,765 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020571758876,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,765 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":1732020571758851,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732020571758879,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571758859,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732020571758881,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571758865,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732020571758884,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732020571760064,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732020571758886,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732020571760070,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732020571758888,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,766 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732020571760544,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732020571758948,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732020571760580,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732020571758950,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020571760583,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,767 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":1732020571759890,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020571761068,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,767 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":1732020571759902,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571761220,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,767 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571759909,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020571761225,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571759915,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020571761228,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732020571761078,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571761231,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732020571761084,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732020571761267,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732020571761560,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732020571761270,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,768 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732020571761594,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020571761274,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020571761597,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020571761430,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020571762072,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020571761453,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571762200,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020571762182,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020571762205,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732020571762243,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,769 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020571762207,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732020571762248,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571762211,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571762253,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732020571762245,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732020571762257,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732020571762248,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732020571765569,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020571762252,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,770 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732020571765575,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020571762401,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020571762422,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571765588,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020571763154,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732020571763213,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732020571763219,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571763223,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732020571763227,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,771 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732020571766530,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,772 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732020571766537,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,772 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571766548,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,773 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":1732020571773563,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,774 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":1732020571773573,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571773582,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.008012262484,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732020571773593,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732020571773599,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732020571773602,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571774472,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732020571774478,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,774 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732020571774493,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-19 12:49:31,775 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":1732020571774507,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,775 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":1732020571774920,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,775 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":1732020571774513,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-19 12:49:31,775 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":1732020571774929,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-19 12:49:31,775 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01234046264,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732020571774593,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,775 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571774938,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,775 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732020571774598,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,775 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.008407095874,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732020571774948,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732020571774623,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732020571774953,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732020571774627,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732020571774957,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:31,776 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":1732020571774635,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571775824,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732020571774641,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-19 12:49:31,776 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732020571775831,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,777 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732020571775843,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-19 12:49:31,777 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":1732020571775856,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,777 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":1732020571775862,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-19 12:49:31,777 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01271840044,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732020571775941,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,777 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732020571775946,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,777 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732020571775969,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571778123,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732020571775973,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571778141,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,778 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":1732020571775981,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732020571778146,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732020571775986,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-19 12:49:31,778 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571778162,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571778609,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3057,"Sub":"","Type":"Debug","_timestamp":1732020571778176,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571778618,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732020571778910,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732020571778622,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4722727762,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732020571778918,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571778636,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,779 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732020571778924,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":0} 2024-11-19 12:49:31,780 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3059,"Sub":"","Type":"Debug","_timestamp":1732020571778648,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:31,780 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732020571779329,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:31,780 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4687303724,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732020571779336,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:31,780 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732020571779342,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":1} 2024-11-19 12:49:31,784 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/1732020570.2954.umwo0a6i/yatmp0kats94a --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/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:49:31,784 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/1732020570.2954.umwo0a6i/yatmpal49gh7f --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/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:49:31,785 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732020571.299173593521118,"finish":1732020571.299827098846436,"duration":0.000653505325317},"execution":{"start":1732020571.299828290939331,"finish":1732020571.784601926803589,"duration":0.484773635864258},"postprocessing":{"start":1732020571.784605741500854,"finish":1732020571.78500509262085,"duration":0.000399351119995}},"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":1732020571.306607961654663,"finish":1732020571.778923988342285,"duration":0.472316026687622},"Load configuration from cache":{"start":1732020571.30678391456604,"finish":1732020571.306797981262207,"duration":0.000014066696167},"Load configuration (no cache)":{"start":1732020571.30710506439209,"finish":1732020571.482124090194702,"duration":0.175019025802612},"Compile and RecalcAll conditions":{"start":1732020571.482150077819824,"finish":1732020571.483781099319458,"duration":0.001631021499634},"Fold global commands":{"start":1732020571.483794927597046,"finish":1732020571.485202074050903,"duration":0.001407146453857},"Load plugins":{"start":1732020571.485212087631226,"finish":1732020571.616790056228638,"duration":0.131577968597412},"ymake main":{"start":1732020571.650536060333252,"finish":1732020571.778909921646118,"duration":0.128373861312866},"Build graph":{"start":1732020571.651573896408081,"finish":1732020571.75403094291687,"duration":0.102457046508789},"Configure graph":{"start":1732020571.651592016220093,"finish":1732020571.7536940574646,"duration":0.102102041244507},"Find Lost Includes":{"start":1732020571.753760099411011,"finish":1732020571.753854036331177,"duration":0.000093936920166},"Set reachable nodes":{"start":1732020571.754179954528809,"finish":1732020571.754816055297852,"duration":0.000636100769043},"Sort edges":{"start":1732020571.754818916320801,"finish":1732020571.757694959640503,"duration":0.002876043319702},"Report Foreign Platform Events":{"start":1732020571.757699012756348,"finish":1732020571.757751941680908,"duration":0.000052928924561},"Check blacklist":{"start":1732020571.75775408744812,"finish":1732020571.757756948471069,"duration":0.000002861022949},"Check isolated projects":{"start":1732020571.757759094238281,"finish":1732020571.75776195526123,"duration":0.000002861022949},"Report Configure Events":{"start":1732020571.757764101028442,"finish":1732020571.757826089859009,"duration":0.000061988830566},"Save and compact":{"start":1732020571.757828950881958,"finish":1732020571.760063886642456,"duration":0.002234935760498},"Detect loops":{"start":1732020571.760070085525513,"finish":1732020571.760580062866211,"duration":0.000509977340698},"Apply Dependency Management":{"start":1732020571.76058292388916,"finish":1732020571.761228084564209,"duration":0.000645160675049},"Save Dependency management cache":{"start":1732020571.761068105697632,"finish":1732020571.761224985122681,"duration":0.000156879425049},"Fill package outputs":{"start":1732020571.761266946792602,"finish":1732020571.761270046234131,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732020571.761274099349976,"finish":1732020571.761430025100708,"duration":0.000155925750732},"Find missing peerdirs":{"start":1732020571.761452913284302,"finish":1732020571.762181997299194,"duration":0.000729084014893},"Export JSON":{"start":1732020571.762243032455444,"finish":1732020571.774622917175293,"duration":0.012379884719849},"Write JSON":{"start":1732020571.762248039245606,"finish":1732020571.774597883224487,"duration":0.012349843978882},"Visit JSON":{"start":1732020571.762257099151611,"finish":1732020571.765568971633911,"duration":0.0033118724823},"Render JSON":{"start":1732020571.765574932098389,"finish":1732020571.773598909378052,"duration":0.008023977279663},"Save Uids cache":{"start":1732020571.77360200881958,"finish":1732020571.774477958679199,"duration":0.000875949859619}},"metrics":{"File access":{"loaded size":9021617,"load time":11325,"loaded MD5 time":844,"Max loaded MD5 time":5,"loaded count":2174,"max load time":58,"mapped size":40632376,"mapped MD5 time":7035,"Max mapped MD5 time":422,"mapped count":130,"map time":853,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3706,"lstat min us":0,"lstat avr us":1,"lstat max us":31,"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":2358,"readdir min us":0,"readdir avr us":0,"readdir max us":42,"(opendir + readdir) sum us":2358,"(lstat + opendir + readdir) sum us":6064},"Parsing":{"parse time":4,"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/1732020570.2954.umwo0a6i/yatmp0kats94a","--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/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.7gb18rrs/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.484773635864258} 2024-11-19 12:49:31,785 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732020571.7858508 2024-11-19 12:49:31,785 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732020571.7858508 2024-11-19 12:49:31,786 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732020571.7858508 2024-11-19 12:49:31,786 DEBUG (core.event_handling.base_subscriber) [Misc-02] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:49:31,786 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732020571.303598403930664,"finish":1732020571.304211378097534,"duration":0.00061297416687},"execution":{"start":1732020571.30421257019043,"finish":1732020571.784968376159668,"duration":0.480755805969238},"postprocessing":{"start":1732020571.784970283508301,"finish":1732020571.786263465881348,"duration":0.001293182373047}},"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":1732020571.310570955276489,"finish":1732020571.77934193611145,"duration":0.468770980834961},"Load configuration from cache":{"start":1732020571.310724020004272,"finish":1732020571.310739040374756,"duration":0.000015020370483},"Load configuration (no cache)":{"start":1732020571.311033010482788,"finish":1732020571.487096071243286,"duration":0.176063060760498},"Compile and RecalcAll conditions":{"start":1732020571.487116098403931,"finish":1732020571.488768100738525,"duration":0.001652002334595},"Fold global commands":{"start":1732020571.48878002166748,"finish":1732020571.490173101425171,"duration":0.00139307975769},"Load plugins":{"start":1732020571.490180969238281,"finish":1732020571.620934963226318,"duration":0.130753993988037},"ymake main":{"start":1732020571.654339075088501,"finish":1732020571.779329061508179,"duration":0.124989986419678},"Build graph":{"start":1732020571.655366897583008,"finish":1732020571.755176067352295,"duration":0.099809169769287},"Configure graph":{"start":1732020571.65538501739502,"finish":1732020571.754844903945923,"duration":0.099459886550903},"Find Lost Includes":{"start":1732020571.754913091659546,"finish":1732020571.755003929138184,"duration":0.000090837478638},"Set reachable nodes":{"start":1732020571.755321979522705,"finish":1732020571.755945920944214,"duration":0.000623941421509},"Sort edges":{"start":1732020571.755949020385742,"finish":1732020571.7588210105896,"duration":0.002871990203857},"Report Foreign Platform Events":{"start":1732020571.758824110031128,"finish":1732020571.758876085281372,"duration":0.000051975250244},"Check blacklist":{"start":1732020571.758878946304321,"finish":1732020571.758881092071533,"duration":0.000002145767212},"Check isolated projects":{"start":1732020571.758883953094482,"finish":1732020571.758886098861694,"duration":0.000002145767212},"Report Configure Events":{"start":1732020571.758888006210327,"finish":1732020571.758948087692261,"duration":0.000060081481934},"Save and compact":{"start":1732020571.758949995040894,"finish":1732020571.761077880859375,"duration":0.002127885818481},"Detect loops":{"start":1732020571.761084079742432,"finish":1732020571.76159405708313,"duration":0.000509977340698},"Apply Dependency Management":{"start":1732020571.761596918106079,"finish":1732020571.76220703125,"duration":0.000610113143921},"Save Dependency management cache":{"start":1732020571.762072086334228,"finish":1732020571.762204885482788,"duration":0.00013279914856},"Fill package outputs":{"start":1732020571.762244939804077,"finish":1732020571.762248039245606,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732020571.76225209236145,"finish":1732020571.762401103973389,"duration":0.000149011611938},"Find missing peerdirs":{"start":1732020571.76242208480835,"finish":1732020571.763154029846191,"duration":0.000731945037842},"Export JSON":{"start":1732020571.76321291923523,"finish":1732020571.7759690284729,"duration":0.012756109237671},"Write JSON":{"start":1732020571.763219118118286,"finish":1732020571.775945901870728,"duration":0.012726783752441},"Visit JSON":{"start":1732020571.763226985931396,"finish":1732020571.76653003692627,"duration":0.003303050994873},"Render JSON":{"start":1732020571.766536951065064,"finish":1732020571.77495288848877,"duration":0.008415937423706},"Save Uids cache":{"start":1732020571.774956941604614,"finish":1732020571.775830984115601,"duration":0.000874042510986}},"metrics":{"File access":{"loaded size":9021617,"load time":10618,"loaded MD5 time":834,"Max loaded MD5 time":5,"loaded count":2174,"max load time":83,"mapped size":40632376,"mapped MD5 time":6740,"Max mapped MD5 time":405,"mapped count":130,"map time":780,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3101,"lstat min us":0,"lstat avr us":1,"lstat max us":14,"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":2173,"readdir min us":0,"readdir avr us":0,"readdir max us":44,"(opendir + readdir) sum us":2173,"(lstat + opendir + readdir) sum us":5274},"Parsing":{"parse time":3,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/yatmpal49gh7f","--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/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.wzoirbef/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.480755805969238} 2024-11-19 12:49:31,786 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732020571.7868738 2024-11-19 12:49:31,786 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732020571.7868738 2024-11-19 12:49:31,786 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732020571.7868738 2024-11-19 12:49:31,787 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732020571.7870646 2024-11-19 12:49:31,787 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732020571.7870646 2024-11-19 12:49:31,787 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732020571.7870646 2024-11-19 12:49:31,787 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.7873354 2024-11-19 12:49:31,787 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished=1732020571.7873354 2024-11-19 12:49:31,787 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732020571.7873354 2024-11-19 12:49:31,787 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.787643 2024-11-19 12:49:31,787 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started=1732020571.787643 2024-11-19 12:49:31,787 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started - 1732020571.787643 2024-11-19 12:49:31,787 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732020571.7878342 2024-11-19 12:49:31,787 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732020571.7878342 2024-11-19 12:49:31,787 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732020571.7878342 2024-11-19 12:49:31,788 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.788136 2024-11-19 12:49:31,788 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished=1732020571.788136 2024-11-19 12:49:31,788 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished - 1732020571.788136 2024-11-19 12:49:31,788 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.7884586 2024-11-19 12:49:31,788 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_started=1732020571.7884586 2024-11-19 12:49:31,793 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-19 12:49:31,793 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_started - 1732020571.7884586 2024-11-19 12:49:31,793 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.00040268898010253906 2024-11-19 12:49:31,793 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.7935174 2024-11-19 12:49:31,793 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished=1732020571.7935174 2024-11-19 12:49:31,793 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-19 12:49:31,793 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732020571.7935174 2024-11-19 12:49:31,793 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.00019431114196777344 2024-11-19 12:49:31,794 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.794038 2024-11-19 12:49:31,794 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished=1732020571.794038 2024-11-19 12:49:31,794 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished - 1732020571.794038 2024-11-19 12:49:31,794 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732020571.794366 2024-11-19 12:49:31,794 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished=1732020571.794366 2024-11-19 12:49:31,794 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732020571.794366 2024-11-19 12:49:31,794 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732020571.7947917 2024-11-19 12:49:31,794 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished=1732020571.7947917 2024-11-19 12:49:31,794 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished - 1732020571.7947917 2024-11-19 12:49:31,795 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-relwithdebinfo: 0.02960371971130371 2024-11-19 12:49:31,795 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732020571.7953823 2024-11-19 12:49:31,795 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732020571.7953823 2024-11-19 12:49:31,795 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732020571.7953823 2024-11-19 12:49:31,795 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732020571.795648 2024-11-19 12:49:31,795 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732020571.795648 2024-11-19 12:49:31,795 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732020571.795648 2024-11-19 12:49:31,795 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-relwithdebinfo: 0.0005347728729248047 2024-11-19 12:49:31,795 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732020571.7958393 2024-11-19 12:49:31,795 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732020571.7958393 2024-11-19 12:49:31,795 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732020571.7958393 2024-11-19 12:49:31,795 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732020571.7959833 2024-11-19 12:49:31,796 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732020571.7959833 2024-11-19 12:49:31,796 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732020571.7959833 2024-11-19 12:49:31,796 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-relwithdebinfo: 0.00035953521728515625 2024-11-19 12:49:31,796 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-19 12:49:31,796 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732020571.796274 2024-11-19 12:49:31,796 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732020571.796274 2024-11-19 12:49:31,796 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732020571.796274 2024-11-19 12:49:31,796 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732020571.7964375 2024-11-19 12:49:31,796 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732020571.7964375 2024-11-19 12:49:31,796 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732020571.7964375 2024-11-19 12:49:31,796 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-relwithdebinfo: 0.0004239082336425781 2024-11-19 12:49:31,796 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-19 12:49:31,796 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732020571.7966611 2024-11-19 12:49:31,796 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732020571.7966611 2024-11-19 12:49:31,796 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732020571.7966611 2024-11-19 12:49:31,796 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-19 12:49:31,797 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732020571.7974195 2024-11-19 12:49:31,797 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732020571.7974195 2024-11-19 12:49:31,797 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732020571.7974195 2024-11-19 12:49:31,797 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732020571.7979274 2024-11-19 12:49:31,797 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732020571.7979274 2024-11-19 12:49:31,798 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732020571.7979274 2024-11-19 12:49:31,798 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-19 12:49:31,814 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-19 12:49:31,947 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-19 12:49:31,947 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-19 12:49:31,947 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732020571.9478953 2024-11-19 12:49:31,947 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732020571.9478953 2024-11-19 12:49:31,948 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732020571.9478953 2024-11-19 12:49:31,948 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732020571.948093 2024-11-19 12:49:31,948 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732020571.948093 2024-11-19 12:49:31,948 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732020571.948093 2024-11-19 12:49:31,948 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 0x7f349f1cd800>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/makefiles.dart'} 2024-11-19 12:49:31,948 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-19 12:49:31,948 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-19 12:49:31,948 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:49:31,948 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-19 12:49:31,949 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/1732020570.2954.umwo0a6i/yatmpbnwih2i5 --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/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' with env: { } 2024-11-19 12:49:32,337 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/1732020570.2954.umwo0a6i/yatmpbnwih2i5 --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/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' finished 2024-11-19 12:49:32,338 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:49:32,338 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732020571.948513746261597,"finish":1732020571.94910478591919,"duration":0.000591039657593},"execution":{"start":1732020571.949105501174927,"finish":1732020572.338097333908081,"duration":0.388991832733154},"postprocessing":{"start":1732020572.338099718093872,"finish":1732020572.338472127914429,"duration":0.000372409820557}},"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/1732020570.2954.umwo0a6i/yatmpbnwih2i5","--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/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/gen_graph.g8aojqvk/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.388991832733154} 2024-11-19 12:49:32,338 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732020572.3388846 2024-11-19 12:49:32,338 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732020572.3388846 2024-11-19 12:49:32,339 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732020572.3388846 2024-11-19 12:49:32,339 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732020572.3391426 2024-11-19 12:49:32,339 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732020572.3391426 2024-11-19 12:49:32,339 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732020572.3391426 2024-11-19 12:49:32,339 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732020572.3393397 2024-11-19 12:49:32,339 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732020572.3393397 2024-11-19 12:49:32,339 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732020572.3393397 2024-11-19 12:49:32,339 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732020572.3396127 2024-11-19 12:49:32,339 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732020572.3396127 2024-11-19 12:49:32,339 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732020572.3396127 2024-11-19 12:49:32,340 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732020572.3402493 2024-11-19 12:49:32,340 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732020572.3402493 2024-11-19 12:49:32,340 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732020572.3402493 2024-11-19 12:49:32,342 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732020572.3421133 2024-11-19 12:49:32,342 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732020572.3421133 2024-11-19 12:49:32,342 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732020572.3421133 2024-11-19 12:49:32,342 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732020572.3423586 2024-11-19 12:49:32,342 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732020572.3423586 2024-11-19 12:49:32,342 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732020572.3423586 2024-11-19 12:49:32,342 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732020572.3425379 2024-11-19 12:49:32,342 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732020572.3425379 2024-11-19 12:49:32,342 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732020572.3425379 2024-11-19 12:49:32,343 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732020572.3432715 2024-11-19 12:49:32,343 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732020572.3432715 2024-11-19 12:49:32,343 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732020572.3432715 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f349cd19ee0>' on 0 suites 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-19 12:49:32,343 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-19 12:49:32,344 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-19 12:49:32,344 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-19 12:49:32,344 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-19 12:49:32,344 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-19 12:49:32,344 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-19 12:49:32,344 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-19 12:49:32,344 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-19 12:49:32,344 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-relwithdebinfo: 0.0010831356048583984 2024-11-19 12:49:32,344 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-relwithdebinfo 2024-11-19 12:49:32,344 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732020572.3446178 2024-11-19 12:49:32,344 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732020572.3446178 2024-11-19 12:49:32,344 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732020572.3446178 2024-11-19 12:49:32,344 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732020572.3447828 2024-11-19 12:49:32,344 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732020572.3447828 2024-11-19 12:49:32,344 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732020572.3447828 2024-11-19 12:49:32,344 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732020572.34492 2024-11-19 12:49:32,344 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732020572.34492 2024-11-19 12:49:32,345 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732020572.34492 2024-11-19 12:49:32,345 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-19 12:49:32,345 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:49:32,345 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-19 12:49:32,345 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-19 12:49:32,345 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732020572.3459136 2024-11-19 12:49:32,345 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732020572.3459136 2024-11-19 12:49:32,346 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732020572.3459136 2024-11-19 12:49:32,346 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732020572.3460789 2024-11-19 12:49:32,346 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732020572.3460789 2024-11-19 12:49:32,346 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732020572.3460789 2024-11-19 12:49:32,346 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732020572.3462367 2024-11-19 12:49:32,346 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732020572.3462367 2024-11-19 12:49:32,346 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732020572.3462367 2024-11-19 12:49:32,346 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.2333221435546875 2024-11-19 12:49:32,346 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732020572.346433 2024-11-19 12:49:32,346 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732020572.346433 2024-11-19 12:49:32,346 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732020572.346433 2024-11-19 12:49:32,346 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 12:49:32,346 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00025653839111328125 2024-11-19 12:49:32,346 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732020572.3466804 2024-11-19 12:49:32,346 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732020572.3466804 2024-11-19 12:49:32,346 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732020572.3466804 2024-11-19 12:49:32,346 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.000213623046875 2024-11-19 12:49:32,346 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:49:32,347 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:49:32,347 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732020572.347158 2024-11-19 12:49:32,347 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732020572.347158 2024-11-19 12:49:32,347 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732020572.347158 2024-11-19 12:49:32,347 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-19 12:49:32,347 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 9.059906005859375e-06 2024-11-19 12:49:32,347 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732020572.347408 2024-11-19 12:49:32,347 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732020572.347408 2024-11-19 12:49:32,347 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732020572.347408 2024-11-19 12:49:32,347 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0007100105285644531 2024-11-19 12:49:32,347 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732020572.3476095 2024-11-19 12:49:32,347 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732020572.3476095 2024-11-19 12:49:32,347 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732020572.3476095 2024-11-19 12:49:32,347 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732020572.347736 2024-11-19 12:49:32,347 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732020572.347736 2024-11-19 12:49:32,347 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732020572.347736 2024-11-19 12:49:32,347 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732020572.3478754 2024-11-19 12:49:32,347 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732020572.3478754 2024-11-19 12:49:32,347 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732020572.3478754 2024-11-19 12:49:32,348 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:32,348 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:32,348 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:32,348 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732020572.3481905 2024-11-19 12:49:32,348 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732020572.3481905 2024-11-19 12:49:32,348 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732020572.3481905 2024-11-19 12:49:32,348 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732020572.3483372 2024-11-19 12:49:32,348 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732020572.3483372 2024-11-19 12:49:32,348 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732020572.3483372 2024-11-19 12:49:32,348 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732020572.3486476 2024-11-19 12:49:32,348 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732020572.3486476 2024-11-19 12:49:32,348 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732020572.3486476 2024-11-19 12:49:32,348 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732020572.3487992 2024-11-19 12:49:32,348 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732020572.3487992 2024-11-19 12:49:32,348 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732020572.3487992 2024-11-19 12:49:32,348 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.001392364501953125 2024-11-19 12:49:32,349 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732020572.3490005 2024-11-19 12:49:32,349 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732020572.3490005 2024-11-19 12:49:32,349 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732020572.3490005 2024-11-19 12:49:32,349 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732020572.3492403 2024-11-19 12:49:32,349 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732020572.3492403 2024-11-19 12:49:32,349 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732020572.3492403 2024-11-19 12:49:32,349 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732020572.349558 2024-11-19 12:49:32,349 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732020572.349558 2024-11-19 12:49:32,349 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732020572.349558 2024-11-19 12:49:32,349 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732020572.3497171 2024-11-19 12:49:32,349 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732020572.3497171 2024-11-19 12:49:32,349 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732020572.3497171 2024-11-19 12:49:32,349 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732020572.3499165 2024-11-19 12:49:32,349 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732020572.3499165 2024-11-19 12:49:32,350 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732020572.3499165 2024-11-19 12:49:32,350 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732020572.3500535 2024-11-19 12:49:32,350 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732020572.3500535 2024-11-19 12:49:32,350 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732020572.3500535 2024-11-19 12:49:32,350 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.2738313674926758 2024-11-19 12:49:32,351 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732020572.3518784 2024-11-19 12:49:32,351 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732020572.3518784 2024-11-19 12:49:32,352 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732020572.3518784 2024-11-19 12:49:32,352 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-19 12:49:32,352 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-19 12:49:32,352 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-19 12:49:32,352 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732020572.35224 2024-11-19 12:49:32,352 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732020572.35224 2024-11-19 12:49:32,352 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732020572.35224 2024-11-19 12:49:32,352 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732020572.352406 2024-11-19 12:49:32,352 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732020572.352406 2024-11-19 12:49:32,352 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732020572.352406 2024-11-19 12:49:32,352 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-19 12:49:32,352 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732020572.3526406 2024-11-19 12:49:32,352 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732020572.3526406 2024-11-19 12:49:32,352 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732020572.3526406 2024-11-19 12:49:32,352 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-19 12:49:32,354 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-19 12:49:32,355 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-19 12:49:32,357 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-19 12:49:32,358 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-19 12:49:32,359 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-19 12:49:32,360 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-19 12:49:32,361 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-19 12:49:32,362 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-19 12:49:32,363 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-19 12:49:32,364 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-19 12:49:32,365 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-19 12:49:32,367 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-19 12:49:32,368 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-19 12:49:32,375 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,375 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,376 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing x13BoArcUwHddoVQObm1pA => True 2024-11-19 12:49:32,376 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/7171326467564654667750306853713970774275766700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,376 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,376 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,377 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => False 2024-11-19 12:49:32,378 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing qq2dgVFTfwP0hSq9pwBuvg => True 2024-11-19 12:49:32,378 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => False 2024-11-19 12:49:32,379 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing 5vSUP7xx7_sGVhNfS-km_A => True 2024-11-19 12:49:32,379 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,380 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,380 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,380 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => False 2024-11-19 12:49:32,381 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 12:49:32,381 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing TWfzylkshIvzaCsGbWaxMw => True 2024-11-19 12:49:32,382 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,382 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing ih2geYwXGIUhuuZo3QsYNg => True 2024-11-19 12:49:32,383 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,383 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 12:49:32,384 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,384 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => False 2024-11-19 12:49:32,384 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,384 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 12:49:32,385 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,385 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => False 2024-11-19 12:49:32,385 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-19 12:49:32,386 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 12:49:32,390 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732020572.3906727 2024-11-19 12:49:32,390 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732020572.3906727 2024-11-19 12:49:32,390 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732020572.3906727 2024-11-19 12:49:32,390 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.040566444396972656 2024-11-19 12:49:32,391 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732020572.3912199 2024-11-19 12:49:32,391 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732020572.3912199 2024-11-19 12:49:32,391 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732020572.3912199 2024-11-19 12:49:32,391 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732020572.391428 2024-11-19 12:49:32,391 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732020572.391428 2024-11-19 12:49:32,391 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732020572.391428 2024-11-19 12:49:32,391 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:49:32,391 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:49:32,392 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:49:32,392 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:49:32,392 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:49:32,392 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:49:32,392 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:49:32,393 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-19 12:49:32,393 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-19 12:49:32,393 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732020572.393762 2024-11-19 12:49:32,393 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732020572.393762 2024-11-19 12:49:32,393 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732020572.393762 2024-11-19 12:49:32,394 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-19 12:49:32,406 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732020570.2954.umwo0a6i/2954b1hlbyva', '0'] 2024-11-19 12:49:32,407 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-19 12:49:32,433 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/d76b/STAMP 2024-11-19 12:49:32,434 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 4.792213439941406e-05 2024-11-19 12:49:32,434 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00017118453979492188 2024-11-19 12:49:32,434 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 7.081031799316406e-05 2024-11-19 12:49:32,434 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 6.628036499023438e-05 2024-11-19 12:49:32,435 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-19 12:49:32,592 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-19 12:49:32,597 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid 0EJ29ZH9qXeF1jYfRxjQ2Q) address: (2535, 18189, 'unix:/home/runner/.ya/build/cache/7/psingxarb6h.tmp') 2024-11-19 12:49:32,606 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 0EJ29ZH9qXeF1jYfRxjQ2Q created (provider=None) 2024-11-19 12:49:32,606 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for n5uplU7Z0Po5gBvTJepGKA created (provider=None) 2024-11-19 12:49:32,606 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for JEg5zyabCbFGnncxktrv6Q created (provider=None) 2024-11-19 12:49:32,606 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for gLiW3WdrL8W13k1bS1kWjA created (provider=None) 2024-11-19 12:49:32,606 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for z1Gg2bMKisFXD7IS9wq_ZQ created (provider=None) 2024-11-19 12:49:32,606 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-19 12:49:32,607 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-19 12:49:32,608 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,608 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => False 2024-11-19 12:49:32,609 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-005] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-19 12:49:32,610 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,610 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-019] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-19 12:49:32,610 DEBUG (yalibrary.toolscache) [Worker-005] Tools cache attempt: (request for resource 7458630270) 2024-11-19 12:49:32,610 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,611 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => False 2024-11-19 12:49:32,611 DEBUG (yalibrary.toolscache) [Worker-019] Tools cache attempt: (request for resource 7336031045) 2024-11-19 12:49:32,611 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => False 2024-11-19 12:49:32,612 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,613 DEBUG (yalibrary.toolscache) [Worker-005] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:49:32,614 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => False 2024-11-19 12:49:32,614 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:32,615 DEBUG (yalibrary.toolscache) [Worker-019] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:49:32,616 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:49:32,618 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => False 2024-11-19 12:49:32,619 DEBUG (library.python.filelock) [Worker-005] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:49:32,619 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:49:32,620 DEBUG (yalibrary.fetcher.cache_helper) [Worker-019] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:49:32,620 DEBUG (library.python.filelock) [Worker-019] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:49:32,620 DEBUG (exts.http_client) [Worker-005] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.wvdpdv45.9x1e8ntr.part, expect md5- 2024-11-19 12:49:32,620 DEBUG (yalibrary.fetcher.cache_helper) [Worker-019] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:49:32,623 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,623 DEBUG (exts.http_client) [Worker-019] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.z0kkr7aq.ail9g8am.part, expect md5- 2024-11-19 12:49:32,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing TWfzylkshIvzaCsGbWaxMw => True 2024-11-19 12:49:32,630 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,631 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,636 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:32,638 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing 5vSUP7xx7_sGVhNfS-km_A => True 2024-11-19 12:49:32,639 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing ih2geYwXGIUhuuZo3QsYNg => True 2024-11-19 12:49:32,639 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing x13BoArcUwHddoVQObm1pA => True 2024-11-19 12:49:32,645 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 200 560 2024-11-19 12:49:32,647 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 200 550 2024-11-19 12:49:32,648 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 200 554 2024-11-19 12:49:32,649 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 200 531 2024-11-19 12:49:32,653 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/df316c9c47a4139b89174d1c0f472ac4fe2867296b3fd41cc090829266a2c339 HTTP/1.1" 200 45 2024-11-19 12:49:32,656 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/bc0b79ff8c144ce34e5bee4a1835bb7996660dbfb47fc2cc059c8a5181b74f92 HTTP/1.1" 200 45 2024-11-19 12:49:32,656 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/3211539310ecb111f5ebcc499e01a7b0864b766970752842733a2b7a0a2e62e5 HTTP/1.1" 200 45 2024-11-19 12:49:32,658 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/25409ebcf7810dd8953d3aa3775a0d3e079f201978962afac21c9294a78ec436 HTTP/1.1" 200 45 2024-11-19 12:49:32,661 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/f30ae64f8ecfe4653ccac9e4ddaa6f637d723c7750dd2b21e1909d8b06037893 HTTP/1.1" 200 268 2024-11-19 12:49:32,665 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/82950daf50cb417d774e6a55dfbfbe5272f3e20b7f345976447bd1a066ad3ea7 HTTP/1.1" 200 265 2024-11-19 12:49:32,666 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/2e1b1170f7afde5aacbe10a2578c1d6713ca0efe6485a4f5c24e951d5d436a7b HTTP/1.1" 200 263 2024-11-19 12:49:32,667 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/fdd0df07f032b9a43166167f32ba25b07405b718073fd57f69fe389e94d593cc HTTP/1.1" 200 254 2024-11-19 12:49:32,670 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/4694fbe9f0be287275dd84e71dbaf1c68de592600ef2d49864444a03bc28bf21 HTTP/1.1" 200 None 2024-11-19 12:49:32,673 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/fe76655ab499b929b4bf8685cc27cc96531e7991d550d6e979ff42675af54032 HTTP/1.1" 200 None 2024-11-19 12:49:32,675 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/58f3708acb9e36b8b2d1a3df6c8072fbcca0ad694dec813ccd67023fc850235f HTTP/1.1" 200 None 2024-11-19 12:49:32,676 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/7de3322f785fdbecde53d1357c21fa25ea7f2371034ddc78101b7849befd5dc4 HTTP/1.1" 200 None 2024-11-19 12:49:32,700 DEBUG (exts.http_client) [Worker-019] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Tue, 19 Nov 2024 12:49:32 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: feb3b770114c17c8 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 12:49:32,721 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (put uid TWfzylkshIvzaCsGbWaxMw) 2024-11-19 12:49:32,725 DEBUG (exts.http_client) [Worker-005] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Tue, 19 Nov 2024 12:49:32 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: 48c0c5146e4a55e5 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 12:49:32,737 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid ih2geYwXGIUhuuZo3QsYNg) 2024-11-19 12:49:32,737 DEBUG (yalibrary.toolscache) [Worker-011] AC cache attempt: (put uid 5vSUP7xx7_sGVhNfS-km_A) 2024-11-19 12:49:32,835 DEBUG (exts.http_client) [Worker-019] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.z0kkr7aq, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.211751 2024-11-19 12:49:32,835 DEBUG (yalibrary.fetcher.common) [Worker-019] extract /home/runner/.ya/tools/v4/7336031045/resource.z0kkr7aq to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-19 12:49:33,027 DEBUG (library.python.filelock) [Worker-019] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:49:33,181 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid x13BoArcUwHddoVQObm1pA) 2024-11-19 12:49:43,914 DEBUG (exts.http_client) [Worker-005] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.wvdpdv45, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=11.292562 2024-11-19 12:49:43,914 DEBUG (yalibrary.fetcher.common) [Worker-005] extract /home/runner/.ya/tools/v4/7458630270/resource.wvdpdv45 to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-19 12:49:44,527 DEBUG (library.python.filelock) [Worker-005] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:49:44,529 DEBUG (yalibrary.toolscache) [Worker-005] AC cache attempt: (get uid cbdc45b99648f0050fb02eadb96cea57) 2024-11-19 12:49:44,530 DEBUG (yalibrary.toolscache) [Worker-066] AC cache attempt: (get uid 855a5e99dfc0a49d33a003086b27e039) 2024-11-19 12:49:44,530 DEBUG (yalibrary.toolscache) [Worker-064] AC cache attempt: (get uid 128adf7b301992c3e0e97b02412f134d) 2024-11-19 12:49:44,530 DEBUG (yalibrary.toolscache) [Worker-065] AC cache attempt: (get uid 2f8dd47722a8e03cbd129229b8540ccd) 2024-11-19 12:49:44,540 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/7a56d63bc0fffbea394e4e91354d3d5b36537934b390d8792def42965b9be811 HTTP/1.1" 404 0 2024-11-19 12:49:44,541 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing cbdc45b99648f0050fb02eadb96cea57 => False 2024-11-19 12:49:44,541 DEBUG (urllib3.connectionpool) [Worker-065] http://cachesrv.internal:8081 "HEAD /ac/e3c42273c4870215995a727fc9f1b7a9643d669195afc39642120adb72de4a11 HTTP/1.1" 404 0 2024-11-19 12:49:44,542 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-065] Bazel-remote Probing 2f8dd47722a8e03cbd129229b8540ccd => False 2024-11-19 12:49:44,542 DEBUG (urllib3.connectionpool) [Worker-064] http://cachesrv.internal:8081 "HEAD /ac/94c5a0cd2e06c2cde331c7784c10d80d6624da77138bf0423aaac083ea7c2b11 HTTP/1.1" 404 0 2024-11-19 12:49:44,542 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-064] Bazel-remote Probing 128adf7b301992c3e0e97b02412f134d => False 2024-11-19 12:49:44,543 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "HEAD /ac/69b61fcb781f10830fe9c5968f0db89e0fe4a6a56877b0b4406edd82ebb46511 HTTP/1.1" 404 0 2024-11-19 12:49:44,543 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-066] Bazel-remote Probing 855a5e99dfc0a49d33a003086b27e039 => False 2024-11-19 12:49:44,545 DEBUG (root) [Worker-005] Run node n5uplU7Z0Po5gBvTJepGKA in build root /home/runner/.ya/build/build_root/d76b/00000a 2024-11-19 12:49:44,547 DEBUG (root) [Worker-065] Run node JEg5zyabCbFGnncxktrv6Q in build root /home/runner/.ya/build/build_root/d76b/000009 2024-11-19 12:49:44,548 DEBUG (root) [Worker-064] Run node z1Gg2bMKisFXD7IS9wq_ZQ in build root /home/runner/.ya/build/build_root/d76b/00000d 2024-11-19 12:49:44,548 DEBUG (root) [Worker-066] Run node gLiW3WdrL8W13k1bS1kWjA in build root /home/runner/.ya/build/build_root/d76b/00000f 2024-11-19 12:49:50,670 DEBUG (yalibrary.toolscache) [Worker-070] AC cache attempt: (put uid gLiW3WdrL8W13k1bS1kWjA) 2024-11-19 12:49:50,671 DEBUG (yalibrary.runner.tasks.result) [Worker-072] Result node gLiW3WdrL8W13k1bS1kWjA processed successfully: True 2024-11-19 12:49:50,672 DEBUG (yalibrary.toolscache) [Worker-070] AC cache attempt: (put uid 855a5e99dfc0a49d33a003086b27e039) 2024-11-19 12:49:50,676 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:50,677 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-066] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => True 2024-11-19 12:49:50,809 DEBUG (yalibrary.toolscache) [Worker-084] AC cache attempt: (put uid JEg5zyabCbFGnncxktrv6Q) 2024-11-19 12:49:50,812 DEBUG (yalibrary.runner.tasks.result) [Worker-079] Result node JEg5zyabCbFGnncxktrv6Q processed successfully: True 2024-11-19 12:49:50,813 DEBUG (yalibrary.toolscache) [Worker-084] AC cache attempt: (put uid 2f8dd47722a8e03cbd129229b8540ccd) 2024-11-19 12:49:50,816 DEBUG (yalibrary.toolscache) [Worker-090] AC cache attempt: (put uid z1Gg2bMKisFXD7IS9wq_ZQ) 2024-11-19 12:49:50,817 DEBUG (urllib3.connectionpool) [Worker-065] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:50,818 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-065] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => True 2024-11-19 12:49:50,818 DEBUG (yalibrary.toolscache) [Worker-090] AC cache attempt: (put uid 128adf7b301992c3e0e97b02412f134d) 2024-11-19 12:49:50,819 DEBUG (yalibrary.runner.tasks.result) [Worker-064] Result node z1Gg2bMKisFXD7IS9wq_ZQ processed successfully: True 2024-11-19 12:49:50,824 DEBUG (urllib3.connectionpool) [Worker-088] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:50,824 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-088] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => True 2024-11-19 12:50:07,886 DEBUG (yalibrary.toolscache) [Worker-103] AC cache attempt: (put uid n5uplU7Z0Po5gBvTJepGKA) 2024-11-19 12:50:07,887 DEBUG (yalibrary.toolscache) [Worker-099] AC cache attempt: (get uid da1e99d39090d2c43e4cf2121e603174) 2024-11-19 12:50:07,888 DEBUG (yalibrary.runner.tasks.result) [Worker-005] Result node n5uplU7Z0Po5gBvTJepGKA processed successfully: True 2024-11-19 12:50:07,888 DEBUG (yalibrary.toolscache) [Worker-103] AC cache attempt: (put uid cbdc45b99648f0050fb02eadb96cea57) 2024-11-19 12:50:07,893 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:50:07,894 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => True 2024-11-19 12:50:07,897 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/ac1a574cdcdae62c090befd1e09565eca34656b2033bf19020ed3e8e87da0411 HTTP/1.1" 404 0 2024-11-19 12:50:07,897 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing da1e99d39090d2c43e4cf2121e603174 => False 2024-11-19 12:50:07,973 DEBUG (root) [Worker-099] Run node 0EJ29ZH9qXeF1jYfRxjQ2Q in build root /home/runner/.ya/build/build_root/d76b/000008 2024-11-19 12:51:06,456 DEBUG (yalibrary.toolscache) [Worker-114] AC cache attempt: (put uid 0EJ29ZH9qXeF1jYfRxjQ2Q) 2024-11-19 12:51:06,457 DEBUG (yalibrary.runner.tasks.result) [Worker-113] Result node 0EJ29ZH9qXeF1jYfRxjQ2Q processed successfully: True 2024-11-19 12:51:06,459 DEBUG (yalibrary.toolscache) [Worker-114] AC cache attempt: (put uid da1e99d39090d2c43e4cf2121e603174) 2024-11-19 12:51:06,463 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:51:06,464 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => True 2024-11-19 12:51:06,535 DEBUG (yalibrary.runner.build_root) [Worker-120] Build root /home/runner/.ya/build/build_root/d76b created=16 left=7 2024-11-19 12:51:06,535 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/d76b/STAMP 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(gLiW3WdrL8W13k1bS1kWjA$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(JEg5zyabCbFGnncxktrv6Q$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/tests-build-default.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(z1Gg2bMKisFXD7IS9wq_ZQ$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(n5uplU7Z0Po5gBvTJepGKA$(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/gtm.js COPY Copying file _assets/logo.svg COPY Copying file _assets/tags.css COPY Copying file _assets/ydb-promo ... d: ru/changelog-server.md INFO Processing finished: ru/changelog-cli.md INFO Processing finished: ru/security-changelog.md COPY Copying file _assets/tags.css 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(0EJ29ZH9qXeF1jYfRxjQ2Q$(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... opying file search/langs/tr.js COPY Copying file search/langs/vi.js COPY Copying file vendor.css COPY Copying file vendor.js COPY Copying file vendor.rtl.css 2024-11-19 12:51:06,549 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-19 12:51:06,550 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.005282163619995,"qty":1},"prepare_all_nodes":{"sum":0.013717651367188,"qty":1},"compact_cache":{"sum":0.001152753829956,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.971031665802002,"qty":9},"prepare[DO]":{"sum":0.005411386489868,"qty":4},"put_in_cache[DO]":{"sum":0.042482137680054,"qty":9},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.417299032211304,"qty":1},"pattern[YFM_TOOL-2315971726]":{"sum":11.918188095092774,"qty":1},"DO":{"sum":100.626766920089722,"qty":5},"result[DO]":{"sum":0.015042304992676,"qty":5},"write_through_caches[DO]":{"sum":0.156639814376831,"qty":5},"clean_build_root":{"sum":0.00031590461731,"qty":1}},"all":{"qty":43,"sum":114.173329830169678},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732020572.609688997268677,1732020584.52787709236145],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":93.956794023513794,"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-19 12:51:06,550 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-19 12:51:06,550 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732020666.5504386 2024-11-19 12:51:06,550 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732020666.5504386 2024-11-19 12:51:06,550 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732020666.5504386 2024-11-19 12:51:06,550 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732020666.5508535 2024-11-19 12:51:06,550 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732020666.5508535 2024-11-19 12:51:06,550 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732020666.5508535 2024-11-19 12:51:06,567 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-19 12:51:06,567 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-19 12:51:06,567 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,567 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,567 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-19 12:51:06,567 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-19 12:51:06,567 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-19 12:51:06,570 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-19 12:51:06,583 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,583 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,585 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,585 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:51:06,585 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732020666.5858471 2024-11-19 12:51:06,585 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732020666.5858471 2024-11-19 12:51:06,585 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732020666.5858471 2024-11-19 12:51:06,586 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-19 12:51:06,587 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-19 12:51:06,587 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-19 12:51:06,587 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 139149312 TotalSize: 139093661 2024-11-19 12:51:06,587 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732020666.5878751 2024-11-19 12:51:06,587 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732020666.5878751 2024-11-19 12:51:06,587 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732020666.5878751 2024-11-19 12:51:06,588 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 45. 2024-11-19 12:51:06,588 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-19 12:51:06,588 DEBUG (build.stat.statistics) [MainThread] Run tasks 13: 9 cached tasks (0 cache(s) resolved by dynamic uids), 5 not cached, 0 failed 2024-11-19 12:51:06,588 DEBUG (build.stat.statistics) [MainThread] Not cached 5: 0 tests, 5 not tests 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 64.28571428571429 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 14 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 9 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 5 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 5 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -1 2024-11-19 12:51:06,589 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",23315,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",58457,"local"]] 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 81885 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 81772 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 81772 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-19 12:51:06,590 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 58457 2024-11-19 12:51:06,591 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-19 12:51:06,591 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-19 12:51:06,591 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-19 12:51:06,591 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-19 12:51:06,591 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 100406 2024-11-19 12:51:06,592 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732020666.5923173 2024-11-19 12:51:06,592 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732020666.5923173 2024-11-19 12:51:06,592 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732020666.5923173 2024-11-19 12:51:06,593 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732020666.5930378 2024-11-19 12:51:06,593 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732020666.5930378 2024-11-19 12:51:06,593 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732020666.5930378 2024-11-19 12:51:06,593 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 8 entries 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-19 12:51:06,593 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:51:06,593 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:51:06,593 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:51:06,593 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:51:06,594 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:51:06,594 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:51:06,594 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:51:06,594 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:51:06,594 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-19 12:51:06,594 DEBUG (StoredReport) [MainThread] Finish report 2024-11-19 12:51:06,594 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 6.842613220214844e-05}, 'BuildResultsListener': {'duration_sec': 0.0009999275207519531}} 2024-11-19 12:51:06,594 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-19 12:51:06,594 INFO (build.reports.build_reports) [MainThread] Dump junit report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/junit.xml 2024-11-19 12:51:06,594 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:51:06,594 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:51:06,594 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:51:06,595 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:51:06,595 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:51:06,595 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:51:06,595 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:51:06,595 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-19 12:51:06,595 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-19 12:51:06,596 INFO (build.reports.build_reports) [MainThread] Dump results report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/report.json 2024-11-19 12:51:06,596 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732020666.5963085 2024-11-19 12:51:06,596 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732020666.5963085 2024-11-19 12:51:06,596 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732020666.5963085 2024-11-19 12:51:06,597 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-19 12:51:06,597 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732020666.597522 2024-11-19 12:51:06,597 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732020666.597522 2024-11-19 12:51:06,597 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732020666.597522 2024-11-19 12:51:06,597 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732020666.5977492 2024-11-19 12:51:06,597 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732020666.5977492 2024-11-19 12:51:06,597 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732020666.5977492 2024-11-19 12:51:06,598 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732020666.5984228 2024-11-19 12:51:06,598 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732020666.5984228 2024-11-19 12:51:06,598 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732020666.5984228 2024-11-19 12:51:06,598 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732020666.5986078 2024-11-19 12:51:06,598 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732020666.5986078 2024-11-19 12:51:06,598 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732020666.5986078 2024-11-19 12:51:06,598 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732020666.598805 2024-11-19 12:51:06,598 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732020666.598805 2024-11-19 12:51:06,598 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732020666.598805 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000102 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000026 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000005 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000002 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000021 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000001 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000009 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000002 2024-11-19 12:51:06,599 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000005 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000009 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000038 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000010 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000002 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000003 2024-11-19 12:51:06,600 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir']) 2024-11-19 12:51:06,600 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732020570.2954.umwo0a6i 2024-11-19 12:51:06,601 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-19 12:51:06,601 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000710 2024-11-19 12:51:06,601 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-19 12:51:06,601 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000006 2024-11-19 12:51:06,601 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000007 2024-11-19 12:51:06,601 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000008 2024-11-19 12:51:06,602 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001125 2024-11-19 12:51:06,602 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000007 2024-11-19 12:51:06,602 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000005 2024-11-19 12:51:06,602 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000006 2024-11-19 12:51:06,603 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-19 12:51:06,603 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000009 2024-11-19 12:51:06,603 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000004 2024-11-19 12:51:06,603 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])