2024-11-19 12:49:15,777 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-19 12:49:15,778 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-19 12:49:15,778 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000772 2024-11-19 12:49:15,778 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:49:15,778 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:15,778 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:15,778 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:49:15,778 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000225 2024-11-19 12:49:15,778 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,778 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000414 2024-11-19 12:49:15,778 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000001 2024-11-19 12:49:15,778 DEBUG (root) [MainThread] Start up timestamp 2024-11-19T12:49:15.UTC (2024-11-19T12:49:15.UTC) 2024-11-19 12:49:15,779 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000035 2024-11-19 12:49:15,779 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000005 2024-11-19 12:49:15,779 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000397 2024-11-19 12:49:15,779 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732020555.2940.2v2vzvss instead of None 2024-11-19 12:49:15,779 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000127 2024-11-19 12:49:15,779 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732020555.7797184 2024-11-19 12:49:15,779 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732020555.7797184 2024-11-19 12:49:15,779 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732020555.7797184 2024-11-19 12:49:15,780 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732020555.7801657 2024-11-19 12:49:15,780 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732020555.7801657 2024-11-19 12:49:15,780 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732020555.7801657 2024-11-19 12:49:15,780 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-19 12:49:15,792 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-19 12:49:15,792 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-19 12:49:15,793 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:15,793 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:49:15,793 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-19 12:49:15,793 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-19 12:49:15,794 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:15,794 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-19 12:49:15,794 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:15,794 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:49:15,794 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-19 12:49:15,805 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:15,805 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:15,806 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732020555.8067684 2024-11-19 12:49:15,806 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732020555.8067684 2024-11-19 12:49:15,806 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732020555.8067684 2024-11-19 12:49:15,806 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732020555.8069026 2024-11-19 12:49:15,806 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732020555.8069026 2024-11-19 12:49:15,806 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732020555.8069026 2024-11-19 12:49:15,807 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:15,807 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:15,807 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-19 12:49:15,807 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-19 12:49:15,807 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000267 2024-11-19 12:49:15,809 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,809 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:15,809 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:49:15,809 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,809 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:49:15,809 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,809 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002247 2024-11-19 12:49:15,810 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000216 2024-11-19 12:49:15,810 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000042 2024-11-19 12:49:15,810 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000899 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000032 2024-11-19 12:49:15,811 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:49:15,811 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:15,811 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:15,811 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000219 2024-11-19 12:49:15,811 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-19 12:49:15,811 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000099 2024-11-19 12:49:15,811 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:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000080 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-19 12:49:15,811 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000002 2024-11-19 12:49:15,812 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:49:15,812 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:15,812 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:49:15,813 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001096 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000060 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000140 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-19 12:49:15,813 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000036 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000004 2024-11-19 12:49:15,813 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000248 2024-11-19 12:49:15,814 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:15,814 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000395 2024-11-19 12:49:15,852 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.038383 2024-11-19 12:49:15,853 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-19 12:49:15,853 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732020555.853421 2024-11-19 12:49:15,853 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732020555.853421 2024-11-19 12:49:15,853 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732020555.853421 2024-11-19 12:49:15,853 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732020555.8535762 2024-11-19 12:49:15,853 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732020555.8535762 2024-11-19 12:49:15,853 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732020555.8535762 2024-11-19 12:49:15,978 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732020555.977997 2024-11-19 12:49:15,978 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732020555.977997 2024-11-19 12:49:15,978 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732020555.977997 2024-11-19 12:49:15,978 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732020555.9782286 2024-11-19 12:49:15,978 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732020555.9782286 2024-11-19 12:49:15,978 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732020555.9782286 2024-11-19 12:49:15,978 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732020555.9783678 2024-11-19 12:49:15,978 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732020555.9783678 2024-11-19 12:49:15,978 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732020555.9783678 2024-11-19 12:49:15,978 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-19 12:49:15,978 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-19 12:49:15,978 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-19 12:49:15,978 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732020555.9785714 2024-11-19 12:49:15,978 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732020555.9785714 2024-11-19 12:49:15,978 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732020555.9785714 2024-11-19 12:49:15,978 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732020555.978815 2024-11-19 12:49:15,978 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732020555.978815 2024-11-19 12:49:15,978 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732020555.978815 2024-11-19 12:49:15,981 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-19 12:49:15,981 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:15,981 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-19 12:49:15,983 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.6A8biWjv5z' file to obtain bazel remote password 2024-11-19 12:49:15,983 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732020555.9835587 2024-11-19 12:49:15,983 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732020555.9835587 2024-11-19 12:49:15,983 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732020555.9835587 2024-11-19 12:49:15,983 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-19 12:49:15,984 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-19 12:49:15,984 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:15,985 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:49:15,985 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:49:15,985 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-19 12:49:15,987 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-19 12:49:15,989 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:15,989 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:15,989 DEBUG (build.graph) [MainThread] target toolchain: { "bottle_name": "clang18", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "SANITIZER_TYPE": "address" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "platform_name": "DEFAULT-LINUX-X86_64", "tool_var": "CLANG-1735056821" } 2024-11-19 12:49:15,989 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-19 12:49:15,990 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:15,990 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:15,990 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:49:15,990 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:15,990 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-19 12:49:15,992 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-19 12:49:16,000 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-19 12:49:16,000 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-19 12:49:16,000 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:49:16,000 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:16,001 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:16,001 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-19 12:49:16,001 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:16,001 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:49:16,001 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:49:16,001 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:49:16,001 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:49:16,001 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:49:16,001 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:16,004 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2368, 12345, 'unix:/home/runner/.ya/tools/v4/psingTlSkM8.tmp') 2024-11-19 12:49:16,009 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:49:16,009 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:49:16,009 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:49:16,009 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.019923686981201172 2024-11-19 12:49:16,010 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.0101511 2024-11-19 12:49:16,010 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_started=1732020556.0101511 2024-11-19 12:49:16,010 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_started - 1732020556.0101511 2024-11-19 12:49:16,010 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.0105283 2024-11-19 12:49:16,011 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:16,011 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_started=1732020556.0105283 2024-11-19 12:49:16,011 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_started - 1732020556.0105283 2024-11-19 12:49:16,011 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732020556.0106611 2024-11-19 12:49:16,011 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732020556.0106611 2024-11-19 12:49:16,011 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732020556.0106611 2024-11-19 12:49:16,011 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.01123 2024-11-19 12:49:16,011 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started=1732020556.01123 2024-11-19 12:49:16,012 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started - 1732020556.01123 2024-11-19 12:49:16,012 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-19 12:49:16,012 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.0116754 2024-11-19 12:49:16,012 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started=1732020556.0116754 2024-11-19 12:49:16,012 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started - 1732020556.0116754 2024-11-19 12:49:16,012 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-19 12:49:16,012 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732020556.0118864 2024-11-19 12:49:16,012 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732020556.0118864 2024-11-19 12:49:16,013 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732020556.0118864 2024-11-19 12:49:16,013 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:16,015 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:16,016 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-jwjmynsmqq Host information: Linux ghrun-jwjmynsmqq 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-19 12:49:16,016 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:16.000000Z\",\n \"BUILD_HOST\": \"ghrun-jwjmynsmqq\",\n \"BUILD_TIMESTAMP\": 1732020556,\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-jwjmynsmqq\\n Host information: \\n Linux ghrun-jwjmynsmqq 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:16,016 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.0324404239654541 2024-11-19 12:49:16,044 DEBUG (build.genconf) [Misc-02] Generating conf into /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:49:16,044 DEBUG (build.genconf) [Misc-03] Generating conf into /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:49:16,171 DEBUG (build.genconf) [Misc-03] Copy conf /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf 2024-11-19 12:49:16,172 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-19 12:49:16,172 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.1723003 2024-11-19 12:49:16,172 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished=1732020556.1723003 2024-11-19 12:49:16,172 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished - 1732020556.1723003 2024-11-19 12:49:16,172 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.1724951 2024-11-19 12:49:16,172 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_started=1732020556.1724951 2024-11-19 12:49:16,172 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_started - 1732020556.1724951 2024-11-19 12:49:16,172 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-nopic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7fb21797b880>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/makefiles.dart'} 2024-11-19 12:49:16,172 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-19 12:49:16,173 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-19 12:49:16,173 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:49:16,173 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-19 12:49:16,173 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/1732020555.2940.2v2vzvss/yatmp4u9p6_co --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:49:16,173 DEBUG (build.genconf) [Misc-02] Copy conf /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf 2024-11-19 12:49:16,175 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-19 12:49:16,175 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.1759372 2024-11-19 12:49:16,176 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished=1732020556.1759372 2024-11-19 12:49:16,176 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished - 1732020556.1759372 2024-11-19 12:49:16,176 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.1761744 2024-11-19 12:49:16,176 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_started=1732020556.1761744 2024-11-19 12:49:16,176 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_started - 1732020556.1761744 2024-11-19 12:49:16,176 DEBUG (build.ymake2) [Misc-02] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-pic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7fb21864ff60>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/makefiles.dart'} 2024-11-19 12:49:16,176 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-19 12:49:16,176 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-19 12:49:16,176 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-19 12:49:16,177 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/1732020555.2940.2v2vzvss/yatmp3mx6ss_t --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:49:16,179 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732020556179380,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":0} 2024-11-19 12:49:16,179 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556179481,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,180 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020556179512,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,180 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556179521,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,180 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020556179525,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,180 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732020556179770,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,180 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020556179774,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732020556182042,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556182137,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020556182165,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556182174,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732020556182177,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732020556182427,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,182 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020556182432,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,334 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020556334135,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,334 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020556334157,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,335 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732020556334877,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,335 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020556334894,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,335 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020556335535,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,335 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732020556335548,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,336 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732020556336197,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,336 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732020556336209,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,336 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732020556336679,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,336 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732020556336688,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,337 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732020556337335,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,337 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732020556337343,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,452 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732020556452142,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,452 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732020556452647,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482727,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482750,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482755,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482838,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732020556482843,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483546,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,483 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482906,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,484 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483565,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,484 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482910,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,484 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483570,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,484 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482914,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,484 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483643,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482918,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732020556483648,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482921,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483698,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482924,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483702,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482928,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483705,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556482931,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,485 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483709,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732020556483767,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483712,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556483776,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483716,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732020556483783,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483719,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732020556483787,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556483722,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732020556483792,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732020556484562,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-19 12:49:16,486 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556483797,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556484570,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732020556483801,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732020556484577,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732020556483805,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732020556484581,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732020556483809,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732020556484585,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556484590,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732020556484594,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732020556484597,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-19 12:49:16,487 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732020556484600,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.09809170567,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732020556582705,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09890390955,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732020556582720,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732020556582728,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732020556582741,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556582738,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556582749,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,583 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556582745,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556582755,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020556582793,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020556582801,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,584 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":3043,"Sub":"","Type":"Debug","_timestamp":1732020556582869,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,584 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":3045,"Sub":"","Type":"Debug","_timestamp":1732020556582876,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020556582872,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732020556582879,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9021617; load time = 10727; loaded MD5 time = 725; Max loaded MD5 time = 7; loaded count = 2174; max load time = 21; mapped size = 40632376; mapped MD5 time = 7146; Max mapped MD5 time = 425; mapped count = 130; map time = 791; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3287; lstat min us = 0; lstat avr us = 1; lstat max us = 21; opendir count = 388; opendir sum us = 0; opendir min us = 0; opendir avr us = 0; opendir max us = 0; readdir count = 2837; readdir sum us = 2100; readdir min us = 0; readdir avr us = 0; readdir max us = 36; (opendir + readdir) sum us = 2100; (lstat + opendir + readdir) sum us = 5387;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732020556582905,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,584 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9021617; load time = 10706; loaded MD5 time = 742; Max loaded MD5 time = 26; loaded count = 2174; max load time = 25; mapped size = 40632376; mapped MD5 time = 7122; Max mapped MD5 time = 424; mapped count = 130; map time = 801; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3429; lstat min us = 1; lstat avr us = 1; lstat max us = 9; 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 = 2262; readdir min us = 0; readdir avr us = 0; readdir max us = 39; (opendir + readdir) sum us = 2262; (lstat + opendir + readdir) sum us = 5691;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732020556582911,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2100,"LoadTime":10727,"LoadedCount":2174,"LoadedMD5Time":725,"LoadedSize":9021617,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5387,"LstatMaxUs":21,"LstatMinUs":0,"LstatSumUs":3287,"MapTime":791,"MappedCount":130,"MappedMD5Time":7146,"MappedSize":40632376,"MaxLoadTime":21,"MaxLoadedMD5Time":7,"MaxMappedMD5Time":425,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":36,"ReaddirMinUs":0,"ReaddirSumUs":2100,"_timestamp":1732020556582917,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2262,"LoadTime":10706,"LoadedCount":2174,"LoadedMD5Time":742,"LoadedSize":9021617,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5691,"LstatMaxUs":9,"LstatMinUs":1,"LstatSumUs":3429,"MapTime":801,"MappedCount":130,"MappedMD5Time":7122,"MappedSize":40632376,"MaxLoadTime":25,"MaxLoadedMD5Time":26,"MaxMappedMD5Time":424,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":39,"ReaddirMinUs":0,"ReaddirSumUs":2262,"_timestamp":1732020556582922,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9021617,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732020556582929,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9021617,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732020556582933,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":10727,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732020556582932,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":10706,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732020556582937,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732020556582935,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732020556582939,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,585 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732020556582937,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732020556582942,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":791,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732020556582940,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":801,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732020556582944,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732020556582942,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732020556582947,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732020556582945,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732020556582950,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9021617; load time = 10727; loaded count = 2174; max load time = 21;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732020556582952,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9021617; load time = 10706; loaded count = 2174; max load time = 25;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732020556582957,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":10727,"LoadedCount":2174,"LoadedSize":9021617,"MaxLoadTime":21,"_timestamp":1732020556582956,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-19 12:49:16,586 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":10706,"LoadedCount":2174,"LoadedSize":9021617,"MaxLoadTime":25,"_timestamp":1732020556582961,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 2; 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":1732020556582966,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 1; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732020556582971,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":2,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732020556582971,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":1,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732020556582975,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-19 12:49:16,587 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":1732020556582982,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,587 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":1732020556582986,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732020556582987,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-19 12:49:16,587 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732020556582990,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-19 12:49:16,587 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":1732020556583000,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,587 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":1732020556583005,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732020556583004,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732020556583009,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732020556583009,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732020556583014,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732020556583012,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732020556583016,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-19 12:49:16,588 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":1732020556583018,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,588 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":1732020556583023,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732020556583022,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732020556583027,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-19 12:49:16,588 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732020556583026,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732020556583031,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,589 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":1732020556583134,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,589 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":1732020556583121,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732020556583138,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732020556583125,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732020556583142,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732020556583129,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732020556583145,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732020556583132,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732020556583148,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,589 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732020556583135,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732020556583150,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732020556583137,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020556583159,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020556583147,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020556583727,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732020556583701,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732020556583730,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732020556583703,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732020556586239,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,590 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732020556586198,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020556586242,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020556586200,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020556586288,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732020556586247,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,591 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-release-asan-nopic). Tool targets: set() 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732020556586290,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,591 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-release-asan-pic). Tool targets: set() 2024-11-19 12:49:16,591 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732020556586249,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732020556.5919037 2024-11-19 12:49:16,592 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732020556586251,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,592 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732020556.5919037 2024-11-19 12:49:16,592 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732020556.5919037 2024-11-19 12:49:16,592 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-19 12:49:16,592 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732020556.592412 2024-11-19 12:49:16,592 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732020556.592412 2024-11-19 12:49:16,592 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732020556.592412 2024-11-19 12:49:16,592 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732020556.5925987 2024-11-19 12:49:16,592 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732020556.5925987 2024-11-19 12:49:16,592 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732020556.5925987 2024-11-19 12:49:16,592 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732020556.5928085 2024-11-19 12:49:16,592 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732020556.5928085 2024-11-19 12:49:16,592 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732020556.5928085 2024-11-19 12:49:16,592 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732020556586304,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732020556586253,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732020556586307,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732020556586255,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732020556586309,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732020556586257,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732020556586311,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732020556586321,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732020556586364,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732020556586324,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,593 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732020556586367,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,593 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":1732020556587194,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,594 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":1732020556587212,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,594 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":1732020556587204,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-19 12:49:16,594 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":1732020556587222,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556587211,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556587229,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556587216,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556587234,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732020556588371,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732020556588326,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,594 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732020556588377,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732020556588331,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732020556588772,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732020556588736,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732020556588801,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732020556588767,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020556588804,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020556588770,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020556589218,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020556589186,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556589334,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,595 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556589304,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020556589339,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732020556589308,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020556589341,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732020556589310,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556589344,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556589313,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732020556589375,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732020556589343,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732020556589377,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732020556589345,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020556589380,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,596 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020556589349,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020556589521,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732020556589485,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020556589541,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020556589505,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020556590180,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732020556590236,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732020556590235,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732020556590291,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732020556590240,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,597 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732020556590309,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556590244,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556590314,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732020556590247,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732020556590318,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732020556593093,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732020556593167,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732020556593098,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732020556593171,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556593109,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,598 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556593181,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,600 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":1732020556600378,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,600 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":1732020556600386,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-19 12:49:16,600 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":1732020556600550,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,600 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556600394,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,601 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":1732020556600558,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.007227897312,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732020556600403,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556600566,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732020556600408,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.007472806572,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732020556600574,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732020556600411,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732020556600579,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556601184,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732020556600582,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:49:16,601 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732020556601190,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556601353,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732020556601203,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732020556601360,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,602 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":1732020556601216,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732020556601371,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-19 12:49:16,602 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":1732020556601221,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-19 12:49:16,602 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":1732020556601383,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.0109775608,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732020556601290,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,602 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":1732020556601389,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-19 12:49:16,602 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732020556601294,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01121294647,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732020556601455,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732020556601316,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732020556601460,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732020556601319,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732020556601480,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,603 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":1732020556601327,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732020556601483,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732020556601332,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-19 12:49:16,603 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":1732020556601491,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,603 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732020556601496,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556604139,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556604159,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556604151,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556604176,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732020556604155,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732020556604181,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556604167,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,604 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556604194,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3045,"Sub":"","Type":"Debug","_timestamp":1732020556604179,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3043,"Sub":"","Type":"Debug","_timestamp":1732020556604206,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732020556604764,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732020556604803,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4227219509,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732020556604769,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4254062086,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732020556604810,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732020556604773,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":1} 2024-11-19 12:49:16,605 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732020556604815,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":0} 2024-11-19 12:49:16,609 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/1732020555.2940.2v2vzvss/yatmp3mx6ss_t --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:49:16,610 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/1732020555.2940.2v2vzvss/yatmp4u9p6_co --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:49:16,610 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732020556.176573514938354,"finish":1732020556.177054643630981,"duration":0.000481128692627},"execution":{"start":1732020556.17705512046814,"finish":1732020556.609662532806396,"duration":0.432607412338257},"postprocessing":{"start":1732020556.60966420173645,"finish":1732020556.609805583953857,"duration":0.000141382217407}},"purpose":"default-linux-x86_64-release-asan-pic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732020556.182041883468628,"finish":1732020556.604773044586182,"duration":0.422731161117554},"Load configuration from cache":{"start":1732020556.182164907455444,"finish":1732020556.182177066802978,"duration":0.000012159347534},"Load configuration (no cache)":{"start":1732020556.182431936264038,"finish":1732020556.334877014160156,"duration":0.152445077896118},"Compile and RecalcAll conditions":{"start":1732020556.334893941879272,"finish":1732020556.336196899414062,"duration":0.00130295753479},"Fold global commands":{"start":1732020556.336209058761597,"finish":1732020556.337335109710693,"duration":0.001126050949097},"Load plugins":{"start":1732020556.337342977523804,"finish":1732020556.452142000198364,"duration":0.114799022674561},"ymake main":{"start":1732020556.482842922210693,"finish":1732020556.604763984680176,"duration":0.121921062469482},"Build graph":{"start":1732020556.483792066574097,"finish":1732020556.583030939102173,"duration":0.099238872528076},"Configure graph":{"start":1732020556.483808994293213,"finish":1732020556.582741022109985,"duration":0.098932027816772},"Find Lost Includes":{"start":1732020556.582801103591919,"finish":1732020556.582879066467285,"duration":0.000077962875366},"Set reachable nodes":{"start":1732020556.583147048950195,"finish":1732020556.583700895309448,"duration":0.000553846359253},"Sort edges":{"start":1732020556.58370304107666,"finish":1732020556.586198091506958,"duration":0.002495050430298},"Report Foreign Platform Events":{"start":1732020556.586199998855591,"finish":1732020556.586246967315674,"duration":0.000046968460083},"Check blacklist":{"start":1732020556.586249113082886,"finish":1732020556.586251020431518,"duration":0.000001907348633},"Check isolated projects":{"start":1732020556.586252927780151,"finish":1732020556.586255073547363,"duration":0.000002145767212},"Report Configure Events":{"start":1732020556.586256980895996,"finish":1732020556.586321115493774,"duration":0.000064134597778},"Save and compact":{"start":1732020556.586323976516724,"finish":1732020556.58837103843689,"duration":0.002047061920166},"Detect loops":{"start":1732020556.588376998901367,"finish":1732020556.58880090713501,"duration":0.000423908233643},"Apply Dependency Management":{"start":1732020556.588804006576538,"finish":1732020556.589340925216675,"duration":0.000536918640137},"Save Dependency management cache":{"start":1732020556.589217901229858,"finish":1732020556.589339017868042,"duration":0.000121116638184},"Fill package outputs":{"start":1732020556.589375019073486,"finish":1732020556.589376926422119,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732020556.589380025863648,"finish":1732020556.589520931243896,"duration":0.000140905380249},"Find missing peerdirs":{"start":1732020556.589540958404541,"finish":1732020556.590179920196533,"duration":0.000638961791992},"Export JSON":{"start":1732020556.590234994888306,"finish":1732020556.601480007171631,"duration":0.011245012283325},"Write JSON":{"start":1732020556.590240001678467,"finish":1732020556.601459980010986,"duration":0.01121997833252},"Visit JSON":{"start":1732020556.590246915817261,"finish":1732020556.593092918395996,"duration":0.002846002578735},"Render JSON":{"start":1732020556.593097925186157,"finish":1732020556.600579023361206,"duration":0.007481098175049},"Save Uids cache":{"start":1732020556.600581884384155,"finish":1732020556.601360082626343,"duration":0.000778198242188}},"metrics":{"File access":{"loaded size":9021617,"load time":10706,"loaded MD5 time":742,"Max loaded MD5 time":26,"loaded count":2174,"max load time":25,"mapped size":40632376,"mapped MD5 time":7122,"Max mapped MD5 time":424,"mapped count":130,"map time":801,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3429,"lstat min us":1,"lstat avr us":1,"lstat max us":9,"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":2262,"readdir min us":0,"readdir avr us":0,"readdir max us":39,"(opendir + readdir) sum us":2262,"(lstat + opendir + readdir) sum us":5691},"Parsing":{"parse time":1,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/yatmp3mx6ss_t","--config","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.9nyjrzm7/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.432607412338257} 2024-11-19 12:49:16,610 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732020556.6106474 2024-11-19 12:49:16,610 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732020556.6106474 2024-11-19 12:49:16,610 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732020556.6106474 2024-11-19 12:49:16,610 DEBUG (core.event_handling.base_subscriber) [Misc-03] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:49:16,611 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732020556.172927379608154,"finish":1732020556.173438549041748,"duration":0.000511169433594},"execution":{"start":1732020556.173439264297485,"finish":1732020556.610334396362305,"duration":0.436895132064819},"postprocessing":{"start":1732020556.610336065292358,"finish":1732020556.611048936843872,"duration":0.000712871551514}},"purpose":"default-linux-x86_64-release-asan-nopic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732020556.179379940032959,"finish":1732020556.604815006256104,"duration":0.425435066223144},"Load configuration from cache":{"start":1732020556.179512023925781,"finish":1732020556.179524898529053,"duration":0.000012874603271},"Load configuration (no cache)":{"start":1732020556.179774045944214,"finish":1732020556.334135055541992,"duration":0.154361009597778},"Compile and RecalcAll conditions":{"start":1732020556.33415699005127,"finish":1732020556.335535049438477,"duration":0.001378059387207},"Fold global commands":{"start":1732020556.335547924041748,"finish":1732020556.336678981781006,"duration":0.001131057739258},"Load plugins":{"start":1732020556.336688041687012,"finish":1732020556.452646970748901,"duration":0.11595892906189},"ymake main":{"start":1732020556.483648061752319,"finish":1732020556.604803085327148,"duration":0.121155023574829},"Build graph":{"start":1732020556.484585046768188,"finish":1732020556.583025932312012,"duration":0.098440885543823},"Configure graph":{"start":1732020556.484600067138672,"finish":1732020556.582727909088135,"duration":0.098127841949463},"Find Lost Includes":{"start":1732020556.58279299736023,"finish":1732020556.582871913909912,"duration":0.000078916549683},"Set reachable nodes":{"start":1732020556.58315896987915,"finish":1732020556.58372688293457,"duration":0.00056791305542},"Sort edges":{"start":1732020556.583729982376099,"finish":1732020556.586239099502564,"duration":0.002509117126465},"Report Foreign Platform Events":{"start":1732020556.586241960525513,"finish":1732020556.586287975311279,"duration":0.000046014785767},"Check blacklist":{"start":1732020556.586289882659912,"finish":1732020556.586303949356079,"duration":0.000014066696167},"Check isolated projects":{"start":1732020556.586307048797607,"finish":1732020556.58630895614624,"duration":0.000001907348633},"Report Configure Events":{"start":1732020556.586311101913452,"finish":1732020556.586364030838013,"duration":0.000052928924561},"Save and compact":{"start":1732020556.586366891860962,"finish":1732020556.58832597732544,"duration":0.001959085464478},"Detect loops":{"start":1732020556.588330984115601,"finish":1732020556.588767051696777,"duration":0.000436067581177},"Apply Dependency Management":{"start":1732020556.588769912719726,"finish":1732020556.589309930801392,"duration":0.000540018081665},"Save Dependency management cache":{"start":1732020556.589185953140259,"finish":1732020556.589308023452759,"duration":0.0001220703125},"Fill package outputs":{"start":1732020556.589343070983887,"finish":1732020556.58934497833252,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732020556.589349031448364,"finish":1732020556.589484930038452,"duration":0.000135898590088},"Find missing peerdirs":{"start":1732020556.589504957199097,"finish":1732020556.590235948562622,"duration":0.000730991363525},"Export JSON":{"start":1732020556.590291023254394,"finish":1732020556.601315975189209,"duration":0.011024951934814},"Write JSON":{"start":1732020556.590308904647827,"finish":1732020556.601294040679932,"duration":0.010985136032104},"Visit JSON":{"start":1732020556.590317964553833,"finish":1732020556.593167066574097,"duration":0.002849102020264},"Render JSON":{"start":1732020556.593170881271362,"finish":1732020556.60040807723999,"duration":0.007237195968628},"Save Uids cache":{"start":1732020556.60041093826294,"finish":1732020556.601190090179443,"duration":0.000779151916504}},"metrics":{"File access":{"loaded size":9021617,"load time":10727,"loaded MD5 time":725,"Max loaded MD5 time":7,"loaded count":2174,"max load time":21,"mapped size":40632376,"mapped MD5 time":7146,"Max mapped MD5 time":425,"mapped count":130,"map time":791,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3287,"lstat min us":0,"lstat avr us":1,"lstat max us":21,"opendir count":388,"opendir sum us":0,"opendir min us":0,"opendir avr us":0,"opendir max us":0,"readdir count":2837,"readdir sum us":2100,"readdir min us":0,"readdir avr us":0,"readdir max us":36,"(opendir + readdir) sum us":2100,"(lstat + opendir + readdir) sum us":5387},"Parsing":{"parse time":2,"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/1732020555.2940.2v2vzvss/yatmp4u9p6_co","--config","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.wpovpx5a/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.436895132064819} 2024-11-19 12:49:16,611 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732020556.6115646 2024-11-19 12:49:16,611 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732020556.6115646 2024-11-19 12:49:16,611 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732020556.6115646 2024-11-19 12:49:16,611 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732020556.6117394 2024-11-19 12:49:16,611 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732020556.6117394 2024-11-19 12:49:16,611 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732020556.6117394 2024-11-19 12:49:16,611 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.6119673 2024-11-19 12:49:16,612 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_finished=1732020556.6119673 2024-11-19 12:49:16,612 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_finished - 1732020556.6119673 2024-11-19 12:49:16,612 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.6122208 2024-11-19 12:49:16,612 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_started=1732020556.6122208 2024-11-19 12:49:16,612 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_started - 1732020556.6122208 2024-11-19 12:49:16,612 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732020556.6123784 2024-11-19 12:49:16,612 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732020556.6123784 2024-11-19 12:49:16,612 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732020556.6123784 2024-11-19 12:49:16,612 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.612642 2024-11-19 12:49:16,612 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_finished=1732020556.612642 2024-11-19 12:49:16,612 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_finished - 1732020556.612642 2024-11-19 12:49:16,612 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.61289 2024-11-19 12:49:16,612 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_started=1732020556.61289 2024-11-19 12:49:16,613 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_started - 1732020556.61289 2024-11-19 12:49:16,616 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-19 12:49:16,617 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.0002613067626953125 2024-11-19 12:49:16,617 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-19 12:49:16,617 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.617243 2024-11-19 12:49:16,617 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.00016951560974121094 2024-11-19 12:49:16,617 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_finished=1732020556.617243 2024-11-19 12:49:16,617 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_finished - 1732020556.617243 2024-11-19 12:49:16,617 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.6174777 2024-11-19 12:49:16,617 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_finished=1732020556.6174777 2024-11-19 12:49:16,617 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_finished - 1732020556.6174777 2024-11-19 12:49:16,617 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732020556.6178908 2024-11-19 12:49:16,618 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_finished=1732020556.6178908 2024-11-19 12:49:16,618 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_finished - 1732020556.6178908 2024-11-19 12:49:16,618 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732020556.61827 2024-11-19 12:49:16,618 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_finished=1732020556.61827 2024-11-19 12:49:16,618 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_finished - 1732020556.61827 2024-11-19 12:49:16,618 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-release-asan: 0.02584052085876465 2024-11-19 12:49:16,618 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732020556.6187565 2024-11-19 12:49:16,618 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732020556.6187565 2024-11-19 12:49:16,618 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732020556.6187565 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732020556.6190028 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732020556.6190028 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732020556.6190028 2024-11-19 12:49:16,619 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-release-asan: 0.0004901885986328125 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732020556.6191761 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732020556.6191761 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732020556.6191761 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732020556.6193063 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732020556.6193063 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732020556.6193063 2024-11-19 12:49:16,619 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-release-asan: 0.00032210350036621094 2024-11-19 12:49:16,619 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732020556.6195388 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732020556.6195388 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732020556.6195388 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732020556.6196797 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732020556.6196797 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732020556.6196797 2024-11-19 12:49:16,619 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-release-asan: 0.00034308433532714844 2024-11-19 12:49:16,619 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-19 12:49:16,619 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732020556.6198738 2024-11-19 12:49:16,619 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732020556.6198738 2024-11-19 12:49:16,619 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732020556.6198738 2024-11-19 12:49:16,620 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-19 12:49:16,620 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732020556.6205509 2024-11-19 12:49:16,620 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732020556.6205509 2024-11-19 12:49:16,620 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732020556.6205509 2024-11-19 12:49:16,620 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732020556.620961 2024-11-19 12:49:16,621 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732020556.620961 2024-11-19 12:49:16,621 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732020556.620961 2024-11-19 12:49:16,621 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-19 12:49:16,635 DEBUG (build.genconf) [Misc-04] Generating conf into /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'IS_CROSS_TOOLS=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:49:16,752 DEBUG (build.genconf) [Misc-04] Copy conf /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf 2024-11-19 12:49:16,753 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-19 12:49:16,753 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732020556.753202 2024-11-19 12:49:16,753 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732020556.753202 2024-11-19 12:49:16,753 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732020556.753202 2024-11-19 12:49:16,753 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732020556.7533839 2024-11-19 12:49:16,753 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732020556.7533839 2024-11-19 12:49:16,753 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732020556.7533839 2024-11-19 12:49:16,753 DEBUG (build.ymake2) [Misc-04] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'test_tool_tc1-nopic-global', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3', '/home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7fb218670c20>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/makefiles.dart'} 2024-11-19 12:49:16,753 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-19 12:49:16,753 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-19 12:49:16,753 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:49:16,754 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-19 12:49:16,754 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/1732020555.2940.2v2vzvss/yatmp0_b_ylix --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/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:17,116 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/1732020555.2940.2v2vzvss/yatmp0_b_ylix --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/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:17,116 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:49:17,116 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732020556.753757238388062,"finish":1732020556.754330158233643,"duration":0.000572919845581},"execution":{"start":1732020556.754330635070801,"finish":1732020557.116335391998291,"duration":0.36200475692749},"postprocessing":{"start":1732020557.116337060928345,"finish":1732020557.116654634475708,"duration":0.000317573547363}},"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/1732020555.2940.2v2vzvss/yatmp0_b_ylix","--config","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/gen_graph.09ipxwy7/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.36200475692749} 2024-11-19 12:49:17,117 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732020557.1170368 2024-11-19 12:49:17,117 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732020557.1170368 2024-11-19 12:49:17,117 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732020557.1170368 2024-11-19 12:49:17,117 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732020557.1172822 2024-11-19 12:49:17,117 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732020557.1172822 2024-11-19 12:49:17,117 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732020557.1172822 2024-11-19 12:49:17,117 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732020557.1174679 2024-11-19 12:49:17,117 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732020557.1174679 2024-11-19 12:49:17,117 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732020557.1174679 2024-11-19 12:49:17,117 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732020557.1177177 2024-11-19 12:49:17,117 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732020557.1177177 2024-11-19 12:49:17,117 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732020557.1177177 2024-11-19 12:49:17,118 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732020557.1182778 2024-11-19 12:49:17,118 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732020557.1182778 2024-11-19 12:49:17,118 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732020557.1182778 2024-11-19 12:49:17,120 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732020557.1200483 2024-11-19 12:49:17,120 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732020557.1200483 2024-11-19 12:49:17,120 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732020557.1200483 2024-11-19 12:49:17,120 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732020557.1202624 2024-11-19 12:49:17,120 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732020557.1202624 2024-11-19 12:49:17,120 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732020557.1202624 2024-11-19 12:49:17,120 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732020557.1204205 2024-11-19 12:49:17,120 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732020557.1204205 2024-11-19 12:49:17,120 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732020557.1204205 2024-11-19 12:49:17,121 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732020557.1214528 2024-11-19 12:49:17,121 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732020557.1214528 2024-11-19 12:49:17,121 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732020557.1214528 2024-11-19 12:49:17,121 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-19 12:49:17,121 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7fb2179f1f80>' on 0 suites 2024-11-19 12:49:17,121 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-19 12:49:17,121 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-19 12:49:17,121 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-19 12:49:17,122 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-19 12:49:17,122 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-19 12:49:17,122 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-19 12:49:17,122 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-19 12:49:17,122 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-release-asan: 0.0010418891906738281 2024-11-19 12:49:17,122 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-release-asan 2024-11-19 12:49:17,122 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732020557.122752 2024-11-19 12:49:17,122 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732020557.122752 2024-11-19 12:49:17,122 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732020557.122752 2024-11-19 12:49:17,122 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732020557.122902 2024-11-19 12:49:17,122 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732020557.122902 2024-11-19 12:49:17,122 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732020557.122902 2024-11-19 12:49:17,123 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732020557.123023 2024-11-19 12:49:17,123 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732020557.123023 2024-11-19 12:49:17,123 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732020557.123023 2024-11-19 12:49:17,123 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-19 12:49:17,123 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:49:17,123 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:17,123 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-19 12:49:17,123 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732020557.1238785 2024-11-19 12:49:17,123 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732020557.1238785 2024-11-19 12:49:17,123 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732020557.1238785 2024-11-19 12:49:17,124 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732020557.1240265 2024-11-19 12:49:17,124 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732020557.1240265 2024-11-19 12:49:17,124 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732020557.1240265 2024-11-19 12:49:17,124 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732020557.1241615 2024-11-19 12:49:17,124 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732020557.1241615 2024-11-19 12:49:17,124 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732020557.1241615 2024-11-19 12:49:17,124 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.1145880222320557 2024-11-19 12:49:17,124 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732020557.1243346 2024-11-19 12:49:17,124 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732020557.1243346 2024-11-19 12:49:17,124 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732020557.1243346 2024-11-19 12:49:17,124 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 12:49:17,124 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00022101402282714844 2024-11-19 12:49:17,124 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732020557.1245465 2024-11-19 12:49:17,124 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732020557.1245465 2024-11-19 12:49:17,124 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732020557.1245465 2024-11-19 12:49:17,124 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.00018167495727539062 2024-11-19 12:49:17,124 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:49:17,124 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:49:17,124 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732020557.1249578 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732020557.1249578 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732020557.1249578 2024-11-19 12:49:17,125 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-19 12:49:17,125 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.106231689453125e-06 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732020557.125172 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732020557.125172 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732020557.125172 2024-11-19 12:49:17,125 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006113052368164062 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732020557.1253443 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732020557.1253443 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732020557.1253443 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732020557.125456 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732020557.125456 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732020557.125456 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732020557.1255782 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732020557.1255782 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732020557.1255782 2024-11-19 12:49:17,125 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:17,125 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:17,125 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732020557.1258383 2024-11-19 12:49:17,125 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732020557.1258383 2024-11-19 12:49:17,125 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732020557.1258383 2024-11-19 12:49:17,125 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732020557.125975 2024-11-19 12:49:17,126 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732020557.125975 2024-11-19 12:49:17,126 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732020557.125975 2024-11-19 12:49:17,126 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732020557.1262417 2024-11-19 12:49:17,126 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732020557.1262417 2024-11-19 12:49:17,126 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732020557.1262417 2024-11-19 12:49:17,126 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732020557.1264036 2024-11-19 12:49:17,126 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732020557.1264036 2024-11-19 12:49:17,126 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732020557.1264036 2024-11-19 12:49:17,126 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0012426376342773438 2024-11-19 12:49:17,126 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732020557.1265852 2024-11-19 12:49:17,126 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732020557.1265852 2024-11-19 12:49:17,126 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732020557.1265852 2024-11-19 12:49:17,126 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732020557.126779 2024-11-19 12:49:17,126 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732020557.126779 2024-11-19 12:49:17,126 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732020557.126779 2024-11-19 12:49:17,127 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732020557.1270554 2024-11-19 12:49:17,127 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732020557.1270554 2024-11-19 12:49:17,127 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732020557.1270554 2024-11-19 12:49:17,127 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732020557.1271908 2024-11-19 12:49:17,127 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732020557.1271908 2024-11-19 12:49:17,127 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732020557.1271908 2024-11-19 12:49:17,127 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732020557.127366 2024-11-19 12:49:17,127 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732020557.127366 2024-11-19 12:49:17,127 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732020557.127366 2024-11-19 12:49:17,127 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732020557.1274855 2024-11-19 12:49:17,127 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732020557.1274855 2024-11-19 12:49:17,127 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732020557.1274855 2024-11-19 12:49:17,127 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.148953914642334 2024-11-19 12:49:17,129 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732020557.1291664 2024-11-19 12:49:17,129 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732020557.1291664 2024-11-19 12:49:17,129 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732020557.1291664 2024-11-19 12:49:17,129 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-19 12:49:17,129 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-19 12:49:17,129 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-19 12:49:17,129 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732020557.1294668 2024-11-19 12:49:17,129 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732020557.1294668 2024-11-19 12:49:17,129 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732020557.1294668 2024-11-19 12:49:17,129 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732020557.129608 2024-11-19 12:49:17,129 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732020557.129608 2024-11-19 12:49:17,129 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732020557.129608 2024-11-19 12:49:17,129 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-19 12:49:17,129 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732020557.129808 2024-11-19 12:49:17,129 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732020557.129808 2024-11-19 12:49:17,129 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732020557.129808 2024-11-19 12:49:17,129 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-19 12:49:17,131 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-19 12:49:17,132 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-19 12:49:17,133 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-19 12:49:17,134 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-19 12:49:17,135 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-19 12:49:17,136 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-19 12:49:17,137 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-19 12:49:17,138 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-19 12:49:17,139 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-19 12:49:17,140 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-19 12:49:17,141 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-19 12:49:17,142 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-19 12:49:17,143 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-19 12:49:17,150 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,150 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,150 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,150 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,151 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => False 2024-11-19 12:49:17,151 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => False 2024-11-19 12:49:17,151 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing 5vSUP7xx7_sGVhNfS-km_A => False 2024-11-19 12:49:17,152 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing x13BoArcUwHddoVQObm1pA => False 2024-11-19 12:49:17,153 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/7171326467564654667750306853713970774275766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,153 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,153 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing qq2dgVFTfwP0hSq9pwBuvg => False 2024-11-19 12:49:17,153 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 12:49:17,154 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,154 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => False 2024-11-19 12:49:17,155 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,155 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing TWfzylkshIvzaCsGbWaxMw => False 2024-11-19 12:49:17,157 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,157 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => False 2024-11-19 12:49:17,157 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,157 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 12:49:17,158 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,159 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing ih2geYwXGIUhuuZo3QsYNg => False 2024-11-19 12:49:17,159 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,159 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,159 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => False 2024-11-19 12:49:17,160 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 12:49:17,160 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-19 12:49:17,160 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 12:49:17,164 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732020557.163982 2024-11-19 12:49:17,164 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732020557.163982 2024-11-19 12:49:17,164 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732020557.163982 2024-11-19 12:49:17,164 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.03641510009765625 2024-11-19 12:49:17,164 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732020557.1643689 2024-11-19 12:49:17,164 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732020557.1643689 2024-11-19 12:49:17,164 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732020557.1643689 2024-11-19 12:49:17,164 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732020557.1645331 2024-11-19 12:49:17,164 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732020557.1645331 2024-11-19 12:49:17,164 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732020557.1645331 2024-11-19 12:49:17,164 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:49:17,164 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:49:17,165 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:49:17,165 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:49:17,165 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:49:17,165 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:49:17,165 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:49:17,165 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:49:17,166 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:17,166 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:49:17,166 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:49:17,166 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:49:17,166 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-19 12:49:17,166 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-19 12:49:17,166 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732020557.1664662 2024-11-19 12:49:17,166 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732020557.1664662 2024-11-19 12:49:17,166 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732020557.1664662 2024-11-19 12:49:17,166 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-19 12:49:17,177 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732020555.2940.2v2vzvss/2940ljzu3drh', '0'] 2024-11-19 12:49:17,177 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-19 12:49:17,198 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/ud59/STAMP 2024-11-19 12:49:17,198 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.266334533691406e-05 2024-11-19 12:49:17,198 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.0001316070556640625 2024-11-19 12:49:17,198 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 5.555152893066406e-05 2024-11-19 12:49:17,198 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 5.364418029785156e-05 2024-11-19 12:49:17,199 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-19 12:49:17,304 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-19 12:49:17,308 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid x13BoArcUwHddoVQObm1pA) address: (2526, 17122, 'unix:/home/runner/.ya/build/cache/7/psingx1VMxQ.tmp') 2024-11-19 12:49:17,319 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,320 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,320 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,320 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing x13BoArcUwHddoVQObm1pA => False 2024-11-19 12:49:17,321 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 0EJ29ZH9qXeF1jYfRxjQ2Q created (provider=None) 2024-11-19 12:49:17,321 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing ih2geYwXGIUhuuZo3QsYNg => False 2024-11-19 12:49:17,321 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,322 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => False 2024-11-19 12:49:17,325 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-005] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-19 12:49:17,325 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/7171326467564654667750306853713970774275766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,326 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,326 DEBUG (urllib3.connectionpool) [Worker-019] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,326 DEBUG (urllib3.connectionpool) [Worker-014] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,326 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "HEAD /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,326 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for n5uplU7Z0Po5gBvTJepGKA created (provider=None) 2024-11-19 12:49:17,327 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-028] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-19 12:49:17,327 DEBUG (urllib3.connectionpool) [Worker-022] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:17,328 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing 5vSUP7xx7_sGVhNfS-km_A => False 2024-11-19 12:49:17,329 DEBUG (yalibrary.toolscache) [Worker-005] Tools cache attempt: (request for resource 7336031045) 2024-11-19 12:49:17,330 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing qq2dgVFTfwP0hSq9pwBuvg => False 2024-11-19 12:49:17,330 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => False 2024-11-19 12:49:17,330 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-019] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => False 2024-11-19 12:49:17,330 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-014] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => False 2024-11-19 12:49:17,331 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-018] Bazel-remote Probing TWfzylkshIvzaCsGbWaxMw => False 2024-11-19 12:49:17,331 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for x13BoArcUwHddoVQObm1pA created (provider=None) 2024-11-19 12:49:17,331 DEBUG (yalibrary.toolscache) [Worker-028] Tools cache attempt: (request for resource 7458630270) 2024-11-19 12:49:17,331 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-022] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => False 2024-11-19 12:49:17,334 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:17,336 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,337 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for 5vSUP7xx7_sGVhNfS-km_A created (provider=None) 2024-11-19 12:49:17,338 DEBUG (yalibrary.toolscache) [Worker-028] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:49:17,339 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:49:17,339 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 12:49:17,339 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for qq2dgVFTfwP0hSq9pwBuvg created (provider=None) 2024-11-19 12:49:17,340 DEBUG (yalibrary.fetcher.cache_helper) [Worker-028] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:49:17,340 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for JEg5zyabCbFGnncxktrv6Q created (provider=None) 2024-11-19 12:49:17,340 DEBUG (library.python.filelock) [Worker-005] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:49:17,340 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for TWfzylkshIvzaCsGbWaxMw created (provider=None) 2024-11-19 12:49:17,340 DEBUG (library.python.filelock) [Worker-028] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:49:17,341 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for gLiW3WdrL8W13k1bS1kWjA created (provider=None) 2024-11-19 12:49:17,341 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:49:17,341 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for ih2geYwXGIUhuuZo3QsYNg created (provider=None) 2024-11-19 12:49:17,341 DEBUG (yalibrary.fetcher.cache_helper) [Worker-028] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:49:17,341 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for z1Gg2bMKisFXD7IS9wq_ZQ created (provider=None) 2024-11-19 12:49:17,341 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-19 12:49:17,342 DEBUG (exts.http_client) [Worker-005] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.hszeowi3.5vfn5z3v.part, expect md5- 2024-11-19 12:49:17,342 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-19 12:49:17,343 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,343 DEBUG (exts.http_client) [Worker-028] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.rje1osvw.n7gtuz2z.part, expect md5- 2024-11-19 12:49:17,343 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 12:49:17,350 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:17,350 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-19 12:49:17,356 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-018] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 12:49:17,370 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-19 12:49:17,371 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-19 12:49:17,371 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-19 12:49:17,379 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-19 12:49:17,380 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-19 12:49:17,381 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-19 12:49:17,389 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-19 12:49:17,389 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-19 12:49:17,390 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-19 12:49:17,391 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-19 12:49:17,402 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-19 12:49:17,402 DEBUG (urllib3.connectionpool) [Worker-018] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-19 12:49:17,403 DEBUG (yalibrary.toolscache) [Worker-011] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-19 12:49:17,403 DEBUG (yalibrary.toolscache) [Worker-018] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-19 12:49:17,470 DEBUG (exts.http_client) [Worker-028] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Tue, 19 Nov 2024 12:49:17 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: d7f9b75ba22a7c23 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:17,504 DEBUG (exts.http_client) [Worker-005] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Tue, 19 Nov 2024 12:49:17 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: efa4a051c0742dec 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:17,669 DEBUG (exts.http_client) [Worker-005] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.hszeowi3, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.326886 2024-11-19 12:49:17,669 DEBUG (yalibrary.fetcher.common) [Worker-005] extract /home/runner/.ya/tools/v4/7336031045/resource.hszeowi3 to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-19 12:49:17,821 DEBUG (library.python.filelock) [Worker-005] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:49:17,823 DEBUG (yalibrary.toolscache) [Worker-005] AC cache attempt: (get uid 7c4928867fcec7e4dcc1d12cadecfda9) 2024-11-19 12:49:17,826 DEBUG (yalibrary.toolscache) [Worker-069] AC cache attempt: (get uid c4891d2217e66450e9bfab80e893f34f) 2024-11-19 12:49:17,837 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/28dd67788b13422f0a62fe16dd02c1e9911c1fc2c9a181acce844424806c7f11 HTTP/1.1" 404 0 2024-11-19 12:49:17,837 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "HEAD /ac/8d149698b57d6a13bec020b27d7d1b90c1cf6be298c1aca1ce1d616c0e903f11 HTTP/1.1" 404 0 2024-11-19 12:49:17,837 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing 7c4928867fcec7e4dcc1d12cadecfda9 => False 2024-11-19 12:49:17,838 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-069] Bazel-remote Probing c4891d2217e66450e9bfab80e893f34f => False 2024-11-19 12:49:17,838 DEBUG (root) [Worker-069] Run node x13BoArcUwHddoVQObm1pA in build root /home/runner/.ya/build/build_root/ud59/00000d 2024-11-19 12:49:17,839 DEBUG (root) [Worker-005] Run node qq2dgVFTfwP0hSq9pwBuvg in build root /home/runner/.ya/build/build_root/ud59/000013 2024-11-19 12:49:18,183 DEBUG (yalibrary.toolscache) [Worker-075] AC cache attempt: (put uid qq2dgVFTfwP0hSq9pwBuvg) 2024-11-19 12:49:18,184 DEBUG (yalibrary.runner.tasks.result) [Worker-005] Result node qq2dgVFTfwP0hSq9pwBuvg processed successfully: True 2024-11-19 12:49:18,185 DEBUG (yalibrary.toolscache) [Worker-075] AC cache attempt: (put uid 7c4928867fcec7e4dcc1d12cadecfda9) 2024-11-19 12:49:18,190 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "HEAD /ac/7171326467564654667750306853713970774275766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:18,191 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Bazel-remote Probing qq2dgVFTfwP0hSq9pwBuvg => False 2024-11-19 12:49:18,191 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Put ydb/docs/ru/core/docs-ru-core.docslib(qq2dgVFTfwP0hSq9pwBuvg) to Bazel-remote 2024-11-19 12:49:18,211 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/17cd56d3d5f215f8c80d7d19347fe328ad0f2274e1539c3f4bd7041a185e6d45 HTTP/1.1" 200 0 2024-11-19 12:49:18,224 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/44df1d4bc2bc69a417577bce3713a7b6f01aa83e1ac7c6b2323f137209c21b00 HTTP/1.1" 200 0 2024-11-19 12:49:18,404 DEBUG (yalibrary.runner.tasks.result) [Worker-085] Result node x13BoArcUwHddoVQObm1pA processed successfully: True 2024-11-19 12:49:18,404 DEBUG (yalibrary.toolscache) [Worker-090] AC cache attempt: (put uid x13BoArcUwHddoVQObm1pA) 2024-11-19 12:49:18,406 DEBUG (yalibrary.toolscache) [Worker-090] AC cache attempt: (put uid c4891d2217e66450e9bfab80e893f34f) 2024-11-19 12:49:18,411 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "HEAD /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:18,412 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-069] Bazel-remote Probing x13BoArcUwHddoVQObm1pA => False 2024-11-19 12:49:18,412 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-069] Put ydb/docs/__docs_internal.docslib.fake(x13BoArcUwHddoVQObm1pA) to Bazel-remote 2024-11-19 12:49:18,423 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "PUT /cas/25409ebcf7810dd8953d3aa3775a0d3e079f201978962afac21c9294a78ec436 HTTP/1.1" 200 0 2024-11-19 12:49:18,434 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "PUT /cas/fdd0df07f032b9a43166167f32ba25b07405b718073fd57f69fe389e94d593cc HTTP/1.1" 200 0 2024-11-19 12:49:18,549 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /cas/5b7b75462dbee28db4de95f61b1cd29de3ad090184a8a2b3536938e51e4fb25f HTTP/1.1" 200 0 2024-11-19 12:49:18,560 DEBUG (urllib3.connectionpool) [Worker-073] http://cachesrv.internal:8081 "PUT /ac/7171326467564654667750306853713970774275766700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:18,560 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-073] Put ydb/docs/ru/core/docs-ru-core.docslib(qq2dgVFTfwP0hSq9pwBuvg) size=29634833 to Bazel-remote completed 2024-11-19 12:49:18,948 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "PUT /cas/7de3322f785fdbecde53d1357c21fa25ea7f2371034ddc78101b7849befd5dc4 HTTP/1.1" 200 0 2024-11-19 12:49:18,958 DEBUG (urllib3.connectionpool) [Worker-069] http://cachesrv.internal:8081 "PUT /ac/783133426f41726355774864646f56514f626d31704100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:18,958 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-069] Put ydb/docs/__docs_internal.docslib.fake(x13BoArcUwHddoVQObm1pA) size=51589393 to Bazel-remote completed 2024-11-19 12:49:25,883 DEBUG (exts.http_client) [Worker-028] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.rje1osvw, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=8.539598 2024-11-19 12:49:25,883 DEBUG (yalibrary.fetcher.common) [Worker-028] extract /home/runner/.ya/tools/v4/7458630270/resource.rje1osvw to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-19 12:49:26,217 DEBUG (library.python.filelock) [Worker-028] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:49:26,218 DEBUG (yalibrary.toolscache) [Worker-099] AC cache attempt: (get uid cbdc45b99648f0050fb02eadb96cea57) 2024-11-19 12:49:26,219 DEBUG (yalibrary.toolscache) [Worker-101] AC cache attempt: (get uid 43c72767449d036e818bd86b5d253e6d) 2024-11-19 12:49:26,219 DEBUG (yalibrary.toolscache) [Worker-028] AC cache attempt: (get uid 07911209a75af0811b1bc632d19b3e77) 2024-11-19 12:49:26,221 DEBUG (yalibrary.toolscache) [Worker-102] AC cache attempt: (get uid 3038b3ba4a516ff563e36a1748ac1c80) 2024-11-19 12:49:26,228 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/7a56d63bc0fffbea394e4e91354d3d5b36537934b390d8792def42965b9be811 HTTP/1.1" 404 0 2024-11-19 12:49:26,229 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "HEAD /ac/98dd2dfb24774f3d91c613a2c82389861338e6988043be21936cd90ff5b5da11 HTTP/1.1" 404 0 2024-11-19 12:49:26,229 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing cbdc45b99648f0050fb02eadb96cea57 => False 2024-11-19 12:49:26,229 DEBUG (urllib3.connectionpool) [Worker-028] http://cachesrv.internal:8081 "HEAD /ac/0d66dc499e963e342d6876c070f2e2dcd57aaedd92f7faa237b8f0c3860a2011 HTTP/1.1" 404 0 2024-11-19 12:49:26,230 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Bazel-remote Probing 43c72767449d036e818bd86b5d253e6d => False 2024-11-19 12:49:26,230 DEBUG (urllib3.connectionpool) [Worker-102] http://cachesrv.internal:8081 "HEAD /ac/5c340510cfdf31ae1a698c5435c6fa4f21bdff1446dd985406112340ce6a9b11 HTTP/1.1" 404 0 2024-11-19 12:49:26,230 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-028] Bazel-remote Probing 07911209a75af0811b1bc632d19b3e77 => False 2024-11-19 12:49:26,231 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-102] Bazel-remote Probing 3038b3ba4a516ff563e36a1748ac1c80 => False 2024-11-19 12:49:26,233 DEBUG (root) [Worker-101] Run node 5vSUP7xx7_sGVhNfS-km_A in build root /home/runner/.ya/build/build_root/ud59/000011 2024-11-19 12:49:26,235 DEBUG (root) [Worker-028] Run node ih2geYwXGIUhuuZo3QsYNg in build root /home/runner/.ya/build/build_root/ud59/00000e 2024-11-19 12:49:26,264 DEBUG (root) [Worker-102] Run node TWfzylkshIvzaCsGbWaxMw in build root /home/runner/.ya/build/build_root/ud59/000017 2024-11-19 12:49:26,282 DEBUG (root) [Worker-099] Run node n5uplU7Z0Po5gBvTJepGKA in build root /home/runner/.ya/build/build_root/ud59/000014 2024-11-19 12:49:29,779 DEBUG (yalibrary.toolscache) [Worker-109] AC cache attempt: (put uid 5vSUP7xx7_sGVhNfS-km_A) 2024-11-19 12:49:29,780 DEBUG (yalibrary.toolscache) [Worker-108] AC cache attempt: (get uid 128adf7b301992c3e0e97b02412f134d) 2024-11-19 12:49:29,780 DEBUG (yalibrary.runner.tasks.result) [Worker-106] Result node 5vSUP7xx7_sGVhNfS-km_A processed successfully: True 2024-11-19 12:49:29,780 DEBUG (yalibrary.toolscache) [Worker-109] AC cache attempt: (put uid 43c72767449d036e818bd86b5d253e6d) 2024-11-19 12:49:29,786 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "HEAD /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:29,787 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Bazel-remote Probing 5vSUP7xx7_sGVhNfS-km_A => False 2024-11-19 12:49:29,787 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(5vSUP7xx7_sGVhNfS-km_A) to Bazel-remote 2024-11-19 12:49:29,789 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "HEAD /ac/94c5a0cd2e06c2cde331c7784c10d80d6624da77138bf0423aaac083ea7c2b11 HTTP/1.1" 404 0 2024-11-19 12:49:29,789 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Bazel-remote Probing 128adf7b301992c3e0e97b02412f134d => False 2024-11-19 12:49:29,789 DEBUG (root) [Worker-108] Run node z1Gg2bMKisFXD7IS9wq_ZQ in build root /home/runner/.ya/build/build_root/ud59/000010 2024-11-19 12:49:29,798 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/3211539310ecb111f5ebcc499e01a7b0864b766970752842733a2b7a0a2e62e5 HTTP/1.1" 200 0 2024-11-19 12:49:29,808 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/2e1b1170f7afde5aacbe10a2578c1d6713ca0efe6485a4f5c24e951d5d436a7b HTTP/1.1" 200 0 2024-11-19 12:49:29,833 DEBUG (yalibrary.toolscache) [Worker-120] AC cache attempt: (get uid 2f8dd47722a8e03cbd129229b8540ccd) 2024-11-19 12:49:29,833 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid TWfzylkshIvzaCsGbWaxMw) 2024-11-19 12:49:29,835 DEBUG (yalibrary.toolscache) [Worker-124] AC cache attempt: (put uid 3038b3ba4a516ff563e36a1748ac1c80) 2024-11-19 12:49:29,835 DEBUG (yalibrary.runner.tasks.result) [Worker-119] Result node TWfzylkshIvzaCsGbWaxMw processed successfully: True 2024-11-19 12:49:29,839 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "HEAD /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:29,840 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Bazel-remote Probing TWfzylkshIvzaCsGbWaxMw => False 2024-11-19 12:49:29,840 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(TWfzylkshIvzaCsGbWaxMw) to Bazel-remote 2024-11-19 12:49:29,842 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "HEAD /ac/e3c42273c4870215995a727fc9f1b7a9643d669195afc39642120adb72de4a11 HTTP/1.1" 404 0 2024-11-19 12:49:29,842 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Bazel-remote Probing 2f8dd47722a8e03cbd129229b8540ccd => False 2024-11-19 12:49:29,842 DEBUG (root) [Worker-120] Run node JEg5zyabCbFGnncxktrv6Q in build root /home/runner/.ya/build/build_root/ud59/000016 2024-11-19 12:49:29,850 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/df316c9c47a4139b89174d1c0f472ac4fe2867296b3fd41cc090829266a2c339 HTTP/1.1" 200 0 2024-11-19 12:49:29,860 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/f30ae64f8ecfe4653ccac9e4ddaa6f637d723c7750dd2b21e1909d8b06037893 HTTP/1.1" 200 0 2024-11-19 12:49:29,874 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /cas/58f3708acb9e36b8b2d1a3df6c8072fbcca0ad694dec813ccd67023fc850235f HTTP/1.1" 200 0 2024-11-19 12:49:29,877 DEBUG (yalibrary.toolscache) [Worker-134] AC cache attempt: (put uid ih2geYwXGIUhuuZo3QsYNg) 2024-11-19 12:49:29,877 DEBUG (yalibrary.toolscache) [Worker-135] AC cache attempt: (get uid 855a5e99dfc0a49d33a003086b27e039) 2024-11-19 12:49:29,878 DEBUG (yalibrary.runner.tasks.result) [Worker-028] Result node ih2geYwXGIUhuuZo3QsYNg processed successfully: True 2024-11-19 12:49:29,878 DEBUG (yalibrary.toolscache) [Worker-134] AC cache attempt: (put uid 07911209a75af0811b1bc632d19b3e77) 2024-11-19 12:49:29,884 DEBUG (urllib3.connectionpool) [Worker-101] http://cachesrv.internal:8081 "PUT /ac/3576535550377878375f734756684e66532d6b6d5f4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:29,884 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "HEAD /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:29,884 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-101] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(5vSUP7xx7_sGVhNfS-km_A) size=1092281 to Bazel-remote completed 2024-11-19 12:49:29,884 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Bazel-remote Probing ih2geYwXGIUhuuZo3QsYNg => False 2024-11-19 12:49:29,885 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(ih2geYwXGIUhuuZo3QsYNg) to Bazel-remote 2024-11-19 12:49:29,887 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "HEAD /ac/69b61fcb781f10830fe9c5968f0db89e0fe4a6a56877b0b4406edd82ebb46511 HTTP/1.1" 404 0 2024-11-19 12:49:29,887 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Bazel-remote Probing 855a5e99dfc0a49d33a003086b27e039 => False 2024-11-19 12:49:29,888 DEBUG (root) [Worker-135] Run node gLiW3WdrL8W13k1bS1kWjA in build root /home/runner/.ya/build/build_root/ud59/000019 2024-11-19 12:49:29,895 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/bc0b79ff8c144ce34e5bee4a1835bb7996660dbfb47fc2cc059c8a5181b74f92 HTTP/1.1" 200 0 2024-11-19 12:49:29,905 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/82950daf50cb417d774e6a55dfbfbe5272f3e20b7f345976447bd1a066ad3ea7 HTTP/1.1" 200 0 2024-11-19 12:49:29,922 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /cas/4694fbe9f0be287275dd84e71dbaf1c68de592600ef2d49864444a03bc28bf21 HTTP/1.1" 200 0 2024-11-19 12:49:29,933 DEBUG (urllib3.connectionpool) [Worker-118] http://cachesrv.internal:8081 "PUT /ac/5457667a796c6b736849767a61437347625761784d7700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:29,933 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-118] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(TWfzylkshIvzaCsGbWaxMw) size=1092256 to Bazel-remote completed 2024-11-19 12:49:29,967 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /cas/fe76655ab499b929b4bf8685cc27cc96531e7991d550d6e979ff42675af54032 HTTP/1.1" 200 0 2024-11-19 12:49:29,977 DEBUG (urllib3.connectionpool) [Worker-132] http://cachesrv.internal:8081 "PUT /ac/69683267655977584749556875755a6f335173594e6700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:29,978 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-132] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(ih2geYwXGIUhuuZo3QsYNg) size=1092285 to Bazel-remote completed 2024-11-19 12:49:34,945 DEBUG (yalibrary.toolscache) [Worker-020] AC cache attempt: (put uid gLiW3WdrL8W13k1bS1kWjA) 2024-11-19 12:49:34,946 DEBUG (yalibrary.runner.tasks.result) [Worker-021] Result node gLiW3WdrL8W13k1bS1kWjA processed successfully: True 2024-11-19 12:49:34,947 DEBUG (yalibrary.toolscache) [Worker-020] AC cache attempt: (put uid 855a5e99dfc0a49d33a003086b27e039) 2024-11-19 12:49:34,952 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "HEAD /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:34,952 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Bazel-remote Probing gLiW3WdrL8W13k1bS1kWjA => False 2024-11-19 12:49:34,953 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(gLiW3WdrL8W13k1bS1kWjA) to Bazel-remote 2024-11-19 12:49:34,964 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/d9ca395bf386f67a51a91d97c779d47bd5a127258d8d6f4f54516f4bde90891f HTTP/1.1" 200 0 2024-11-19 12:49:34,974 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/dfea8f0dfdca569aa338e7c24c115a7bf0c2d91534b776374446885f9fdc4664 HTTP/1.1" 200 0 2024-11-19 12:49:35,004 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid z1Gg2bMKisFXD7IS9wq_ZQ) 2024-11-19 12:49:35,005 DEBUG (yalibrary.runner.tasks.result) [Worker-034] Result node z1Gg2bMKisFXD7IS9wq_ZQ processed successfully: True 2024-11-19 12:49:35,006 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid 128adf7b301992c3e0e97b02412f134d) 2024-11-19 12:49:35,011 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "HEAD /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:35,011 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Bazel-remote Probing z1Gg2bMKisFXD7IS9wq_ZQ => False 2024-11-19 12:49:35,012 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(z1Gg2bMKisFXD7IS9wq_ZQ) to Bazel-remote 2024-11-19 12:49:35,022 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/58db1b8f2b23633254e36d579fe350d55fd8bc81ae1f0ea11d2f0619aebfa777 HTTP/1.1" 200 0 2024-11-19 12:49:35,036 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/7604d0a24c575d6b6525300b0bbb6fa59383f79f9272ace76b738bd3144e4269 HTTP/1.1" 200 0 2024-11-19 12:49:35,054 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /cas/3df18e7421ef18041e38c559128d35c19894abdcf6b274895c91c8432b56f5a8 HTTP/1.1" 200 0 2024-11-19 12:49:35,064 DEBUG (urllib3.connectionpool) [Worker-135] http://cachesrv.internal:8081 "PUT /ac/674c6957335764724c385731336b316253316b576a4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:35,065 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-135] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(gLiW3WdrL8W13k1bS1kWjA) size=2779904 to Bazel-remote completed 2024-11-19 12:49:35,102 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (put uid JEg5zyabCbFGnncxktrv6Q) 2024-11-19 12:49:35,103 DEBUG (yalibrary.runner.tasks.result) [Worker-015] Result node JEg5zyabCbFGnncxktrv6Q processed successfully: True 2024-11-19 12:49:35,103 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (put uid 2f8dd47722a8e03cbd129229b8540ccd) 2024-11-19 12:49:35,109 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "HEAD /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:35,109 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Bazel-remote Probing JEg5zyabCbFGnncxktrv6Q => False 2024-11-19 12:49:35,110 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(JEg5zyabCbFGnncxktrv6Q) to Bazel-remote 2024-11-19 12:49:35,114 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /cas/cd917c015e34ae58df7460c0192d5c9fd35b4a41f703a8a5aa22e1a2edac71f1 HTTP/1.1" 200 0 2024-11-19 12:49:35,120 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/f2a818370505476653db24e7ff0ae7e6b9ffa63ed56a6a19d1351b16f7139eeb HTTP/1.1" 200 0 2024-11-19 12:49:35,124 DEBUG (urllib3.connectionpool) [Worker-108] http://cachesrv.internal:8081 "PUT /ac/7a31476732624d4b69734658443749533977715f5a5100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:35,124 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-108] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(z1Gg2bMKisFXD7IS9wq_ZQ) size=2779898 to Bazel-remote completed 2024-11-19 12:49:35,130 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/086f7a6d4cccd838d37064fa52b5fbdabc09741fd3c8c6abd444643a7e8f0b5c HTTP/1.1" 200 0 2024-11-19 12:49:35,163 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /cas/d8bd5aabbbf437146342b27beee25a1d5460c9804b771e8c41f15fc95a807e5b HTTP/1.1" 200 0 2024-11-19 12:49:35,173 DEBUG (urllib3.connectionpool) [Worker-120] http://cachesrv.internal:8081 "PUT /ac/4a4567357a796162436246476e6e63786b747276365100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:35,173 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-120] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(JEg5zyabCbFGnncxktrv6Q) size=2779909 to Bazel-remote completed 2024-11-19 12:49:45,473 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid n5uplU7Z0Po5gBvTJepGKA) 2024-11-19 12:49:45,474 DEBUG (yalibrary.toolscache) [Worker-055] AC cache attempt: (get uid 2be95ef6bc78c9e02360b198fcdd44a5) 2024-11-19 12:49:45,475 DEBUG (yalibrary.runner.tasks.result) [Worker-052] Result node n5uplU7Z0Po5gBvTJepGKA processed successfully: True 2024-11-19 12:49:45,476 DEBUG (yalibrary.toolscache) [Worker-051] AC cache attempt: (put uid cbdc45b99648f0050fb02eadb96cea57) 2024-11-19 12:49:45,481 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "HEAD /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:49:45,481 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Bazel-remote Probing n5uplU7Z0Po5gBvTJepGKA => False 2024-11-19 12:49:45,481 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/docs/preprocessed.tar.gz(n5uplU7Z0Po5gBvTJepGKA) to Bazel-remote 2024-11-19 12:49:45,483 DEBUG (urllib3.connectionpool) [Worker-055] http://cachesrv.internal:8081 "HEAD /ac/451d2b0a4d44b226f91212477be2dadcc4a400b595ba103f5200fb1ba58ccb11 HTTP/1.1" 404 0 2024-11-19 12:49:45,483 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-055] Bazel-remote Probing 2be95ef6bc78c9e02360b198fcdd44a5 => False 2024-11-19 12:49:45,484 DEBUG (root) [Worker-055] Run node 0EJ29ZH9qXeF1jYfRxjQ2Q in build root /home/runner/.ya/build/build_root/ud59/000015 2024-11-19 12:49:45,491 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/124eaf5df8f44e7321e94a0bc35963f5ea939c893c8d013f4a2c7ddc34e19156 HTTP/1.1" 200 0 2024-11-19 12:49:45,503 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/3f40d0cd86b62d317abd53299d24890c8368172e887b661a44ff196e7b566560 HTTP/1.1" 200 0 2024-11-19 12:49:45,839 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /cas/e989d477deaeef1fd4034f28923fcf8480394da7dc3e5e314ca20dfbc63a410a HTTP/1.1" 200 0 2024-11-19 12:49:45,849 DEBUG (urllib3.connectionpool) [Worker-099] http://cachesrv.internal:8081 "PUT /ac/6e3575706c55375a30506f35674276544a6570474b4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:49:45,849 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-099] Put ydb/docs/preprocessed.tar.gz(n5uplU7Z0Po5gBvTJepGKA) size=36209475 to Bazel-remote completed 2024-11-19 12:50:29,062 DEBUG (yalibrary.toolscache) [Worker-062] AC cache attempt: (put uid 0EJ29ZH9qXeF1jYfRxjQ2Q) 2024-11-19 12:50:29,063 DEBUG (yalibrary.runner.tasks.result) [Worker-055] Result node 0EJ29ZH9qXeF1jYfRxjQ2Q processed successfully: True 2024-11-19 12:50:29,063 DEBUG (yalibrary.toolscache) [Worker-062] AC cache attempt: (put uid 2be95ef6bc78c9e02360b198fcdd44a5) 2024-11-19 12:50:29,069 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "HEAD /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:50:29,070 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Bazel-remote Probing 0EJ29ZH9qXeF1jYfRxjQ2Q => False 2024-11-19 12:50:29,070 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Put ydb/docs/ydb-docs.tar.gz(0EJ29ZH9qXeF1jYfRxjQ2Q) to Bazel-remote 2024-11-19 12:50:29,089 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/3c016e361224ab424b0e5090bf44d4ff70731e1c3efffc1457dafa26e75fb7e7 HTTP/1.1" 200 0 2024-11-19 12:50:29,099 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/3392a8daee4edb35fd18aa81bb3575a63c3217113404f0a7f07668b7427cd673 HTTP/1.1" 200 0 2024-11-19 12:50:29,468 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /cas/ab7e20d3d7993cd2fb33be73830cebe4244de3c504b6687d48e54be28b7c3f76 HTTP/1.1" 200 0 2024-11-19 12:50:29,478 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "PUT /ac/30454a32395a483971586546316a596652786a51325100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:50:29,478 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Put ydb/docs/ydb-docs.tar.gz(0EJ29ZH9qXeF1jYfRxjQ2Q) size=39679434 to Bazel-remote completed 2024-11-19 12:50:29,542 DEBUG (yalibrary.runner.build_root) [Worker-076] Build root /home/runner/.ya/build/build_root/ud59 created=25 left=12 2024-11-19 12:50:29,542 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/ud59/STAMP 2024-11-19 12:50:29,552 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(5vSUP7xx7_sGVhNfS-km_A$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 12:50:29,552 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(TWfzylkshIvzaCsGbWaxMw$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 12:50:29,552 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(ih2geYwXGIUhuuZo3QsYNg$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query.png COPY Copying file ydb/d ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-19 12:50:29,552 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:50:29,552 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:50:29,552 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:50:29,552 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:50:29,552 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:50:29,553 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-19 12:50:29,553 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.004226446151733,"qty":1},"prepare_all_nodes":{"sum":0.036903619766235,"qty":1},"compact_cache":{"sum":0.00264048576355,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.392318248748779,"qty":13},"prepare[DO]":{"sum":0.002300262451172,"qty":3},"put_in_cache[DO]":{"sum":0.03490161895752,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.4957115650177,"qty":1},"DO":{"sum":90.169959545135498,"qty":10},"result[DO]":{"sum":0.017741203308105,"qty":10},"write_through_caches[DO]":{"sum":0.087859153747559,"qty":10},"put_in_dist_cache[DO]":{"sum":2.532374143600464,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":8.889959573745728,"qty":1},"clean_build_root":{"sum":0.000279664993286,"qty":1}},"all":{"qty":75,"sum":102.667175531387329},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732020557.327559471130371,1732020566.217519044876099],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":72.248024225234985,"build_type":"release","flags":{"OPENSOURCE":"yes","USE_PREBUILT_TOOLS":"no","APPLE_SDK_LOCAL":"yes","USE_CLANG_CL":"yes","USE_AIO":"static","USE_ICONV":"static","USE_IDN":"static","CFLAGS":"-fno-omit-frame-pointer","USE_EAT_MY_DATA":"yes","DEBUGINFO_LINES_ONLY":"yes","CONSISTENT_DEBUG":"yes","TESTS_REQUESTED":"yes","DISABLE_FLAKE8_MIGRATIONS":"yes"},"rel_targets":["."],"threads":64} 2024-11-19 12:50:29,553 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-19 12:50:29,553 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732020629.553527 2024-11-19 12:50:29,553 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732020629.553527 2024-11-19 12:50:29,553 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732020629.553527 2024-11-19 12:50:29,553 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732020629.5539136 2024-11-19 12:50:29,553 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732020629.5539136 2024-11-19 12:50:29,554 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732020629.5539136 2024-11-19 12:50:29,568 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-19 12:50:29,568 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-19 12:50:29,568 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,569 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,569 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-19 12:50:29,569 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-19 12:50:29,569 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-19 12:50:29,571 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-19 12:50:29,578 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,578 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,579 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,579 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:50:29,579 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732020629.579954 2024-11-19 12:50:29,580 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732020629.579954 2024-11-19 12:50:29,580 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732020629.579954 2024-11-19 12:50:29,580 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-19 12:50:29,581 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-19 12:50:29,581 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-19 12:50:29,581 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168760388 2024-11-19 12:50:29,581 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732020629.5818858 2024-11-19 12:50:29,581 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732020629.5818858 2024-11-19 12:50:29,581 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732020629.5818858 2024-11-19 12:50:29,582 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-19 12:50:29,582 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-19 12:50:29,582 DEBUG (build.stat.statistics) [MainThread] Run tasks 13: 13 cached tasks (0 cache(s) resolved by dynamic uids), 10 not cached, 0 failed 2024-11-19 12:50:29,582 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-19 12:50:29,582 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",555,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",19168,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",43554,"local"]] 2024-11-19 12:50:29,583 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 71200 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 63277 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 63277 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-19 12:50:29,584 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 43554 2024-11-19 12:50:29,585 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-19 12:50:29,585 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-19 12:50:29,585 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-19 12:50:29,585 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-19 12:50:29,585 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 89868 2024-11-19 12:50:29,586 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732020629.5861268 2024-11-19 12:50:29,586 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732020629.5861268 2024-11-19 12:50:29,586 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732020629.5861268 2024-11-19 12:50:29,586 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732020629.5867534 2024-11-19 12:50:29,586 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732020629.5867534 2024-11-19 12:50:29,586 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732020629.5867534 2024-11-19 12:50:29,586 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:50:29,587 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-19 12:50:29,587 DEBUG (StoredReport) [MainThread] Finish report 2024-11-19 12:50:29,587 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 8.106231689453125e-05}, 'BuildResultsListener': {'duration_sec': 0.0011935234069824219}} 2024-11-19 12:50:29,587 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-19 12:50:29,588 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:50:29,588 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:50:29,588 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:50:29,588 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:50:29,588 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:50:29,588 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:50:29,588 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:50:29,588 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:50:29,588 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-19 12:50:29,589 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-19 12:50:29,589 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:50:29,589 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732020629.5895293 2024-11-19 12:50:29,589 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732020629.5895293 2024-11-19 12:50:29,589 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732020629.5895293 2024-11-19 12:50:29,590 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-19 12:50:29,590 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732020629.5906446 2024-11-19 12:50:29,590 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732020629.5906446 2024-11-19 12:50:29,590 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732020629.5906446 2024-11-19 12:50:29,590 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732020629.5908387 2024-11-19 12:50:29,590 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732020629.5908387 2024-11-19 12:50:29,590 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732020629.5908387 2024-11-19 12:50:29,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732020629.591446 2024-11-19 12:50:29,591 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732020629.591446 2024-11-19 12:50:29,591 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732020629.591446 2024-11-19 12:50:29,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732020629.5916162 2024-11-19 12:50:29,591 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732020629.5916162 2024-11-19 12:50:29,591 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732020629.5916162 2024-11-19 12:50:29,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732020629.591824 2024-11-19 12:50:29,591 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732020629.591824 2024-11-19 12:50:29,591 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732020629.591824 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000087 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000028 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000002 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000016 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000007 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000001 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000004 2024-11-19 12:50:29,592 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000006 2024-11-19 12:50:29,593 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000013 2024-11-19 12:50:29,593 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000007 2024-11-19 12:50:29,593 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000002 2024-11-19 12:50:29,593 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000002 2024-11-19 12:50:29,593 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:50:29,593 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732020555.2940.2v2vzvss 2024-11-19 12:50:29,593 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-19 12:50:29,593 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000608 2024-11-19 12:50:29,594 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000009 2024-11-19 12:50:29,594 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000006 2024-11-19 12:50:29,594 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000006 2024-11-19 12:50:29,594 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000007 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001126 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000007 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000004 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000006 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000007 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000004 2024-11-19 12:50:29,595 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])