2024-11-19 12:37:41,325 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-19 12:37:41,326 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000764 2024-11-19 12:37:41,326 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:37:41,326 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:37:41,326 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:37:41,326 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000204 2024-11-19 12:37:41,326 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000421 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-19 12:37:41,326 DEBUG (root) [MainThread] Start up timestamp 2024-11-19T12:37:41.UTC (2024-11-19T12:37:41.UTC) 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000036 2024-11-19 12:37:41,326 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000005 2024-11-19 12:37:41,327 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000407 2024-11-19 12:37:41,327 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw instead of None 2024-11-19 12:37:41,327 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000134 2024-11-19 12:37:41,327 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732019861.3276577 2024-11-19 12:37:41,327 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732019861.3276577 2024-11-19 12:37:41,327 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732019861.3276577 2024-11-19 12:37:41,328 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732019861.3281124 2024-11-19 12:37:41,328 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732019861.3281124 2024-11-19 12:37:41,328 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732019861.3281124 2024-11-19 12:37:41,328 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-19 12:37:41,340 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-19 12:37:41,341 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-19 12:37:41,341 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-19 12:37:41,341 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:37:41,341 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-19 12:37:41,341 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-19 12:37:41,342 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:37:41,342 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-19 12:37:41,342 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:37:41,342 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-19 12:37:41,342 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-19 12:37:41,353 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:37:41,353 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:37:41,354 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732019861.3545485 2024-11-19 12:37:41,354 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732019861.3545485 2024-11-19 12:37:41,354 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732019861.3545485 2024-11-19 12:37:41,354 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732019861.3546753 2024-11-19 12:37:41,354 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732019861.3546753 2024-11-19 12:37:41,354 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732019861.3546753 2024-11-19 12:37:41,354 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:37:41,355 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:37:41,355 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-19 12:37:41,355 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-19 12:37:41,355 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000286 2024-11-19 12:37:41,357 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,357 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:37:41,357 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:37:41,357 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,357 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-19 12:37:41,357 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,357 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002287 2024-11-19 12:37:41,357 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000220 2024-11-19 12:37:41,358 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000046 2024-11-19 12:37:41,358 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000904 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000046 2024-11-19 12:37:41,359 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-19 12:37:41,359 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:37:41,359 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:37:41,359 DEBUG (root) [MainThread] vcs type: git 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000219 2024-11-19 12:37:41,359 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-19 12:37:41,359 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000098 2024-11-19 12:37:41,359 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:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000077 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-19 12:37:41,359 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000001 2024-11-19 12:37:41,360 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-19 12:37:41,360 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:37:41,360 DEBUG (root) [MainThread] Update replacements in 2024-11-19 12:37:41,360 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-19 12:37:41,360 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001098 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000062 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000003 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000135 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-19 12:37:41,361 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000037 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-19 12:37:41,361 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000245 2024-11-19 12:37:41,361 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:37:41,362 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000391 2024-11-19 12:37:41,400 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.038174 2024-11-19 12:37:41,400 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-19 12:37:41,401 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732019861.4011114 2024-11-19 12:37:41,401 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732019861.4011114 2024-11-19 12:37:41,401 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732019861.4011114 2024-11-19 12:37:41,401 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732019861.4012709 2024-11-19 12:37:41,401 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732019861.4012709 2024-11-19 12:37:41,401 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732019861.4012709 2024-11-19 12:37:41,525 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732019861.5255208 2024-11-19 12:37:41,525 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732019861.5255208 2024-11-19 12:37:41,525 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732019861.5255208 2024-11-19 12:37:41,525 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732019861.525731 2024-11-19 12:37:41,525 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732019861.525731 2024-11-19 12:37:41,525 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732019861.525731 2024-11-19 12:37:41,525 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732019861.5258505 2024-11-19 12:37:41,525 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732019861.5258505 2024-11-19 12:37:41,525 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732019861.5258505 2024-11-19 12:37:41,525 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-19 12:37:41,526 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-19 12:37:41,526 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-19 12:37:41,526 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732019861.526063 2024-11-19 12:37:41,526 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732019861.526063 2024-11-19 12:37:41,526 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732019861.526063 2024-11-19 12:37:41,526 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732019861.5262945 2024-11-19 12:37:41,526 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732019861.5262945 2024-11-19 12:37:41,526 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732019861.5262945 2024-11-19 12:37:41,528 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-19 12:37:41,528 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:37:41,528 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-19 12:37:41,530 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.8KKD9n49dy' file to obtain bazel remote password 2024-11-19 12:37:41,530 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732019861.5309424 2024-11-19 12:37:41,531 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732019861.5309424 2024-11-19 12:37:41,531 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732019861.5309424 2024-11-19 12:37:41,531 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-19 12:37:41,531 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-19 12:37:41,532 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:37:41,532 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-19 12:37:41,532 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-19 12:37:41,533 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-19 12:37:41,535 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-19 12:37:41,536 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:37:41,537 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:37:41,537 DEBUG (build.graph) [MainThread] target toolchain: { "bottle_name": "clang18", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "platform_name": "DEFAULT-LINUX-X86_64", "tool_var": "CLANG-1735056821" } 2024-11-19 12:37:41,537 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-19 12:37:41,537 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:37:41,537 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:37:41,537 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:37:41,537 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:37:41,538 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-19 12:37:41,539 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-19 12:37:41,547 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-19 12:37:41,547 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-19 12:37:41,547 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:37:41,548 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:37:41,548 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:37:41,548 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-19 12:37:41,548 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:37:41,548 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:37:41,548 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:37:41,548 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-19 12:37:41,548 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-19 12:37:41,548 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-19 12:37:41,548 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:37:41,551 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2393, 14967, 'unix:/home/runner/.ya/tools/v4/psingBfXZ54.tmp') 2024-11-19 12:37:41,556 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:37:41,556 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:37:41,556 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-19 12:37:41,556 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.01896381378173828 2024-11-19 12:37:41,556 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019861.5567484 2024-11-19 12:37:41,557 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_started=1732019861.5567484 2024-11-19 12:37:41,557 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_started - 1732019861.5567484 2024-11-19 12:37:41,557 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019861.5571058 2024-11-19 12:37:41,557 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started=1732019861.5571058 2024-11-19 12:37:41,557 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_started - 1732019861.5571058 2024-11-19 12:37:41,557 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732019861.5572145 2024-11-19 12:37:41,557 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732019861.5572145 2024-11-19 12:37:41,558 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732019861.5572145 2024-11-19 12:37:41,558 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019861.5578337 2024-11-19 12:37:41,558 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started=1732019861.5578337 2024-11-19 12:37:41,558 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_started - 1732019861.5578337 2024-11-19 12:37:41,558 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-19 12:37:41,558 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019861.5578835 2024-11-19 12:37:41,558 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started=1732019861.5578835 2024-11-19 12:37:41,558 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_started - 1732019861.5578835 2024-11-19 12:37:41,559 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-19 12:37:41,559 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732019861.558109 2024-11-19 12:37:41,559 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732019861.558109 2024-11-19 12:37:41,559 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732019861.558109 2024-11-19 12:37:41,560 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:37:41,562 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:37:41,565 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'f95544f728262cb458bbc83650b7427411dfe58f', author:b'Semyon ', summary:b'Merge 7fc09855422c4569951fddad77635112df9661cc into 73654270aa388c26ddae0ef5e4efc4bcc8896f82', svn_id:b'' 2024-11-19 12:37:41,565 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-6btab6daxy Host information: Linux ghrun-6btab6daxy 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-19 12:37:41,565 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"f95544f728262cb458bbc83650b7427411dfe58f\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"Semyon \",\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:37:41.000000Z\",\n \"BUILD_HOST\": \"ghrun-6btab6daxy\",\n \"BUILD_TIMESTAMP\": 1732019861,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: f95544f728262cb458bbc83650b7427411dfe58f\\n Branch: \\n Author: Semyon \\n Summary: Merge 7fc09855422c4569951fddad77635112df9661cc 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-6btab6daxy\\n Host information: \\n Linux ghrun-6btab6daxy 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: f95544f728262cb458bbc83650b7427411dfe58f\\n Branch: \\n Author: Semyon \\n Summary: Merge 7fc09855422c4569951fddad77635112df9661cc into 73654270aa388c26ddae0ef5e4efc4bcc8896f82\\n\",\n \"VCS\": \"git\"\n}" 2024-11-19 12:37:41,565 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.033983707427978516 2024-11-19 12:37:41,590 DEBUG (build.genconf) [Misc-02] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-98d764e15a8af720dfdf42d2b66ea646/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:37:41,591 DEBUG (build.genconf) [Misc-03] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-3798d7c5263eb68d1e51bedf06eb099e/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:37:41,715 DEBUG (build.genconf) [Misc-02] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-98d764e15a8af720dfdf42d2b66ea646/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf 2024-11-19 12:37:41,715 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-19 12:37:41,715 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019861.7154841 2024-11-19 12:37:41,715 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished=1732019861.7154841 2024-11-19 12:37:41,715 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-pic_finished - 1732019861.7154841 2024-11-19 12:37:41,715 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019861.715672 2024-11-19 12:37:41,715 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started=1732019861.715672 2024-11-19 12:37:41,715 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_started - 1732019861.715672 2024-11-19 12:37:41,715 DEBUG (build.ymake2) [Misc-02] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-relwithdebinfo-pic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f071e368540>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/makefiles.dart'} 2024-11-19 12:37:41,715 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-19 12:37:41,716 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-19 12:37:41,716 DEBUG (core.event_handling.base_subscriber) [Misc-02] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:37:41,716 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-19 12:37:41,716 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/1732019861.2967.i6lqhjqw/yatmpackoz1ic --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:37:41,716 DEBUG (build.genconf) [Misc-03] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-3798d7c5263eb68d1e51bedf06eb099e/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf 2024-11-19 12:37:41,719 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-19 12:37:41,719 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019861.7191918 2024-11-19 12:37:41,719 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished=1732019861.7191918 2024-11-19 12:37:41,719 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732019861.7191918 2024-11-19 12:37:41,719 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019861.719445 2024-11-19 12:37:41,719 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started=1732019861.719445 2024-11-19 12:37:41,719 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_started - 1732019861.719445 2024-11-19 12:37:41,719 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-relwithdebinfo-nopic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f071daa3740>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/makefiles.dart'} 2024-11-19 12:37:41,719 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-19 12:37:41,719 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-19 12:37:41,720 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-19 12:37:41,720 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/1732019861.2967.i6lqhjqw/yatmpuu01v_1g --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-19 12:37:41,722 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732019861722493,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019861722596,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732019861722627,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019861722636,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732019861722640,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732019861722885,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:41,723 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732019861722890,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:41,725 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732019861725438,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":1} 2024-11-19 12:37:41,725 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019861725534,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:41,725 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732019861725564,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:41,725 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019861725572,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:41,726 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732019861725576,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:41,726 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732019861725824,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:41,726 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732019861725829,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:41,875 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732019861875618,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:41,876 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732019861875639,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:41,876 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732019861876935,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:41,877 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732019861876945,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:41,878 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732019861878096,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:41,878 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732019861878104,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:41,879 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732019861879770,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:41,880 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732019861879787,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:41,881 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732019861881265,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:41,881 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732019861881275,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:41,882 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732019861882409,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:41,882 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732019861882417,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:41,992 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732019861992027,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:41,996 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732019861996458,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,022 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022377,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,022 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022394,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,022 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022399,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022480,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732019862022484,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022533,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022537,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022541,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022544,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,023 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022547,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022551,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022554,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862022557,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732019862023359,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862023368,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732019862023374,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732019862023378,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732019862023382,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862023387,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,024 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732019862023392,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-19 12:37:42,025 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732019862023395,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-19 12:37:42,025 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732019862023399,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862026891,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862026908,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862026913,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862026983,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732019862026987,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027032,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027036,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027040,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,027 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027043,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027046,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027050,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027053,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027056,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732019862027858,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027867,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732019862027874,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732019862027877,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732019862027881,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,028 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862027885,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,029 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732019862027889,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-19 12:37:42,029 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732019862027892,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-19 12:37:42,029 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732019862027896,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,118 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09485296454,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732019862118262,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,118 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732019862118282,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,118 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862118291,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,119 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862118297,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732019862118344,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,119 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":3070,"Sub":"","Type":"Debug","_timestamp":1732019862118419,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,119 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732019862118423,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,119 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9024246; load time = 9398; loaded MD5 time = 709; Max loaded MD5 time = 5; loaded count = 2174; max load time = 22; mapped size = 40632376; mapped MD5 time = 7197; Max mapped MD5 time = 453; mapped count = 130; map time = 648; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3177; lstat min us = 0; lstat avr us = 1; lstat max us = 13; 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 = 2032; readdir min us = 0; readdir avr us = 0; readdir max us = 39; (opendir + readdir) sum us = 2032; (lstat + opendir + readdir) sum us = 5209;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732019862118455,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,119 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.09171730675,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732019862119623,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2032,"LoadTime":9398,"LoadedCount":2174,"LoadedMD5Time":709,"LoadedSize":9024246,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5209,"LstatMaxUs":13,"LstatMinUs":0,"LstatSumUs":3177,"MapTime":648,"MappedCount":130,"MappedMD5Time":7197,"MappedSize":40632376,"MaxLoadTime":22,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":453,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":39,"ReaddirMinUs":0,"ReaddirSumUs":2032,"_timestamp":1732019862118466,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732019862119642,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9024246,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732019862118478,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862119649,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9398,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732019862118482,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862119655,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732019862118485,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732019862119699,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,120 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732019862118487,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,120 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":3072,"Sub":"","Type":"Debug","_timestamp":1732019862119772,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":648,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732019862118490,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732019862119776,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732019862118493,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732019862118495,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9024246; load time = 8664; loaded MD5 time = 737; Max loaded MD5 time = 5; loaded count = 2174; max load time = 18; mapped size = 40632376; mapped MD5 time = 5807; Max mapped MD5 time = 362; mapped count = 130; map time = 636; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3085; lstat min us = 0; lstat avr us = 1; lstat max us = 16; 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 = 1812; readdir min us = 0; readdir avr us = 0; readdir max us = 36; (opendir + readdir) sum us = 1812; (lstat + opendir + readdir) sum us = 4897;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732019862119806,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9024246; load time = 9398; loaded count = 2174; max load time = 22;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732019862118502,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":9398,"LoadedCount":2174,"LoadedSize":9024246,"MaxLoadTime":22,"_timestamp":1732019862118507,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-19 12:37:42,121 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":1812,"LoadTime":8664,"LoadedCount":2174,"LoadedMD5Time":737,"LoadedSize":9024246,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":4897,"LstatMaxUs":16,"LstatMinUs":0,"LstatSumUs":3085,"MapTime":636,"MappedCount":130,"MappedMD5Time":5807,"MappedSize":40632376,"MaxLoadTime":18,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":362,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":36,"ReaddirMinUs":0,"ReaddirSumUs":1812,"_timestamp":1732019862119818,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-19 12:37:42,121 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":1732019862118517,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9024246,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732019862119829,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":1,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732019862118521,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":8664,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732019862119833,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,122 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":1732019862118534,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732019862119836,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732019862118538,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732019862119839,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,122 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":1732019862118551,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":636,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732019862119841,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732019862118555,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-19 12:37:42,122 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732019862119844,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732019862118560,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732019862119846,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732019862118563,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9024246; load time = 8664; loaded count = 2174; max load time = 18;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732019862119854,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,123 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":1732019862118569,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":8664,"LoadedCount":2174,"LoadedSize":9024246,"MaxLoadTime":18,"_timestamp":1732019862119858,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732019862118573,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 3; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732019862119868,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732019862118576,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,123 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732019862119872,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-19 12:37:42,124 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":1732019862118676,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,124 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":1732019862119885,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732019862118681,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732019862119890,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732019862118685,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,124 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":1732019862119903,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732019862118688,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732019862119907,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732019862118691,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732019862119912,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,124 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732019862118693,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732019862119915,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-19 12:37:42,125 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":1732019862119922,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732019862118702,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732019862119926,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732019862119267,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732019862119930,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732019862119269,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,125 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":1732019862120020,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732019862121741,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,125 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732019862120025,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732019862121744,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732019862120029,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732019862120032,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732019862121790,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,126 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-relwithdebinfo-pic). Tool targets: set() 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732019862120034,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732019862121793,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732019862120037,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,126 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732019862121795,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732019862121797,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732019862121799,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732019862120046,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732019862121801,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732019862120586,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732019862121854,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732019862120588,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732019862121856,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732019862123212,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,127 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":1732019862122693,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,127 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732019862123216,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,128 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":1732019862122703,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-19 12:37:42,128 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-relwithdebinfo-nopic). Tool targets: set() 2024-11-19 12:37:42,128 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732019862.1282377 2024-11-19 12:37:42,128 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862122710,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,128 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732019862.1282377 2024-11-19 12:37:42,128 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732019862.1282377 2024-11-19 12:37:42,128 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-19 12:37:42,128 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732019862.1287305 2024-11-19 12:37:42,128 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732019862.1287305 2024-11-19 12:37:42,128 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732019862.1287305 2024-11-19 12:37:42,128 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732019862.1289213 2024-11-19 12:37:42,128 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732019862.1289213 2024-11-19 12:37:42,129 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732019862.1289213 2024-11-19 12:37:42,129 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732019862.1291196 2024-11-19 12:37:42,129 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732019862.1291196 2024-11-19 12:37:42,129 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732019862.1291196 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732019862123263,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862122715,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732019862123265,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732019862123796,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732019862123267,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732019862123801,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732019862123269,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732019862124204,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-19 12:37:42,129 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732019862123272,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732019862124235,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732019862123274,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732019862124237,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732019862123327,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732019862124666,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732019862123329,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862124797,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,130 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":1732019862124157,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732019862124801,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,130 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":1732019862124167,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-19 12:37:42,130 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732019862124803,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862124174,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862124806,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862124179,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732019862124837,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732019862125182,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732019862124839,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732019862125188,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732019862124843,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732019862125587,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732019862124977,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,131 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732019862125616,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732019862124996,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732019862125619,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732019862125899,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732019862126037,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732019862125967,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862126146,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732019862125972,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732019862126150,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862125977,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732019862126152,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,132 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732019862125981,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862126155,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732019862128825,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732019862126187,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732019862128829,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732019862126189,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732019862126193,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862128840,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732019862126328,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732019862126347,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732019862126978,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,133 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732019862127032,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732019862127036,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862127040,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732019862127044,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732019862130054,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732019862130063,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,134 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862130074,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,135 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":1732019862135776,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,136 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":1732019862135783,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862135791,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.006966682679,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732019862135800,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732019862135804,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732019862135807,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862136506,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732019862136512,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,136 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732019862136522,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-19 12:37:42,136 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":1732019862136534,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,137 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":1732019862136539,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-19 12:37:42,137 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01062613288,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732019862136601,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,137 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732019862136605,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,137 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":1732019862137037,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,137 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732019862136627,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,137 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":1732019862137045,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-19 12:37:42,137 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732019862136630,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,137 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862137052,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,138 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":1732019862136637,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,138 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.007009585021,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732019862137077,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,139 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732019862136641,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-19 12:37:42,139 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732019862137083,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,139 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732019862137087,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-19 12:37:42,139 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862137810,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,139 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732019862137816,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862139940,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732019862137827,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862139956,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,140 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":1732019862137839,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732019862139961,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-19 12:37:42,140 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":1732019862137844,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862140264,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,140 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01087154125,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732019862137911,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3070,"Sub":"","Type":"Debug","_timestamp":1732019862140278,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732019862137915,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732019862140854,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732019862137936,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4183567009,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732019862140861,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732019862137938,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732019862140866,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-pic","ymake_run_uid":0} 2024-11-19 12:37:42,141 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":1732019862137946,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,141 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732019862137950,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862140639,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862140649,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732019862140654,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862140665,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3072,"Sub":"","Type":"Debug","_timestamp":1732019862140676,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732019862141267,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4158154229,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732019862141275,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-19 12:37:42,142 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732019862141281,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-relwithdebinfo-nopic","ymake_run_uid":1} 2024-11-19 12:37:42,145 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/1732019861.2967.i6lqhjqw/yatmpackoz1ic --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:37:42,146 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/1732019861.2967.i6lqhjqw/yatmpuu01v_1g --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-19 12:37:42,146 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732019861.716089248657226,"finish":1732019861.716588735580444,"duration":0.000499486923218},"execution":{"start":1732019861.716589450836182,"finish":1732019862.145707368850708,"duration":0.429117918014526},"postprocessing":{"start":1732019862.145709991455078,"finish":1732019862.145848751068115,"duration":0.000138759613037}},"purpose":"default-linux-x86_64-relwithdebinfo-pic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732019861.722492933273315,"finish":1732019862.140866041183472,"duration":0.418373107910156},"Load configuration from cache":{"start":1732019861.72262692451477,"finish":1732019861.722640037536621,"duration":0.000013113021851},"Load configuration (no cache)":{"start":1732019861.72288990020752,"finish":1732019861.875617980957031,"duration":0.152728080749512},"Compile and RecalcAll conditions":{"start":1732019861.875638961791992,"finish":1732019861.876935005187988,"duration":0.001296043395996},"Fold global commands":{"start":1732019861.87694501876831,"finish":1732019861.878096103668213,"duration":0.001151084899902},"Load plugins":{"start":1732019861.878103971481323,"finish":1732019861.992027044296265,"duration":0.113923072814941},"ymake main":{"start":1732019862.022484064102173,"finish":1732019862.140853881835938,"duration":0.118369817733765},"Build graph":{"start":1732019862.023381948471069,"finish":1732019862.118576049804688,"duration":0.095194101333618},"Configure graph":{"start":1732019862.023399114608765,"finish":1732019862.118282079696655,"duration":0.094882965087891},"Find Lost Includes":{"start":1732019862.118344068527222,"finish":1732019862.118422985076904,"duration":0.000078916549683},"Set reachable nodes":{"start":1732019862.118701934814453,"finish":1732019862.119266986846924,"duration":0.000565052032471},"Sort edges":{"start":1732019862.119268894195557,"finish":1732019862.121741056442261,"duration":0.002472162246704},"Report Foreign Platform Events":{"start":1732019862.12174391746521,"finish":1732019862.121789932250977,"duration":0.000046014785767},"Check blacklist":{"start":1732019862.121793031692505,"finish":1732019862.121794939041138,"duration":0.000001907348633},"Check isolated projects":{"start":1732019862.12179708480835,"finish":1732019862.121798992156982,"duration":0.000001907348633},"Report Configure Events":{"start":1732019862.121800899505615,"finish":1732019862.121854066848755,"duration":0.00005316734314},"Save and compact":{"start":1732019862.121855974197388,"finish":1732019862.123795986175537,"duration":0.001940011978149},"Detect loops":{"start":1732019862.123800992965698,"finish":1732019862.124234914779663,"duration":0.000433921813965},"Apply Dependency Management":{"start":1732019862.124237060546875,"finish":1732019862.124803066253662,"duration":0.000566005706787},"Save Dependency management cache":{"start":1732019862.124665975570679,"finish":1732019862.12480092048645,"duration":0.000134944915771},"Fill package outputs":{"start":1732019862.124836921691895,"finish":1732019862.124839067459106,"duration":0.000002145767212},"Check Transitive Requirements":{"start":1732019862.124842882156372,"finish":1732019862.124977111816406,"duration":0.000134229660034},"Find missing peerdirs":{"start":1732019862.124995946884155,"finish":1732019862.125899076461792,"duration":0.000903129577637},"Export JSON":{"start":1732019862.125967025756836,"finish":1732019862.136626958847046,"duration":0.01065993309021},"Write JSON":{"start":1732019862.125972032546997,"finish":1732019862.136605024337769,"duration":0.010632991790771},"Visit JSON":{"start":1732019862.125981092453003,"finish":1732019862.128824949264526,"duration":0.002843856811523},"Render JSON":{"start":1732019862.128829002380371,"finish":1732019862.135803937911987,"duration":0.006974935531616},"Save Uids cache":{"start":1732019862.135807037353516,"finish":1732019862.136512041091919,"duration":0.000705003738403}},"metrics":{"File access":{"loaded size":9024246,"load time":9398,"loaded MD5 time":709,"Max loaded MD5 time":5,"loaded count":2174,"max load time":22,"mapped size":40632376,"mapped MD5 time":7197,"Max mapped MD5 time":453,"mapped count":130,"map time":648,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3177,"lstat min us":0,"lstat avr us":1,"lstat max us":13,"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":2032,"readdir min us":0,"readdir avr us":0,"readdir max us":39,"(opendir + readdir) sum us":2032,"(lstat + opendir + readdir) sum us":5209},"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/1732019861.2967.i6lqhjqw/yatmpackoz1ic","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-9ab01bb8ea623bac3a59e142d32bd0cf_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.3nsc7icz/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.429117918014526} 2024-11-19 12:37:42,146 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732019862.1466696 2024-11-19 12:37:42,146 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732019862.1466696 2024-11-19 12:37:42,146 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732019862.1466696 2024-11-19 12:37:42,146 DEBUG (core.event_handling.base_subscriber) [Misc-03] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:37:42,147 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732019861.719862461090088,"finish":1732019861.72036075592041,"duration":0.000498294830322},"execution":{"start":1732019861.720361471176148,"finish":1732019862.146344423294067,"duration":0.42598295211792},"postprocessing":{"start":1732019862.146345615386963,"finish":1732019862.147066593170166,"duration":0.000720977783203}},"purpose":"default-linux-x86_64-relwithdebinfo-nopic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732019861.725438117980957,"finish":1732019862.141280889511108,"duration":0.415842771530151},"Load configuration from cache":{"start":1732019861.725564002990723,"finish":1732019861.725575923919678,"duration":0.000011920928955},"Load configuration (no cache)":{"start":1732019861.725828886032104,"finish":1732019861.879770040512085,"duration":0.15394115447998},"Compile and RecalcAll conditions":{"start":1732019861.879786968231201,"finish":1732019861.881264925003052,"duration":0.001477956771851},"Fold global commands":{"start":1732019861.881274938583374,"finish":1732019861.88240909576416,"duration":0.001134157180786},"Load plugins":{"start":1732019861.88241696357727,"finish":1732019861.996458053588867,"duration":0.114041090011597},"ymake main":{"start":1732019862.026987075805664,"finish":1732019862.14126706123352,"duration":0.114279985427856},"Build graph":{"start":1732019862.027880907058716,"finish":1732019862.119930028915405,"duration":0.092049121856689},"Configure graph":{"start":1732019862.027895927429199,"finish":1732019862.119642019271851,"duration":0.091746091842651},"Find Lost Includes":{"start":1732019862.119699001312256,"finish":1732019862.119776010513306,"duration":0.00007700920105},"Set reachable nodes":{"start":1732019862.120045900344849,"finish":1732019862.120585918426514,"duration":0.000540018081665},"Sort edges":{"start":1732019862.120588064193726,"finish":1732019862.123212099075317,"duration":0.002624034881592},"Report Foreign Platform Events":{"start":1732019862.123215913772583,"finish":1732019862.123262882232666,"duration":0.000046968460083},"Check blacklist":{"start":1732019862.123265027999878,"finish":1732019862.123266935348511,"duration":0.000001907348633},"Check isolated projects":{"start":1732019862.123269081115723,"finish":1732019862.123271942138672,"duration":0.000002861022949},"Report Configure Events":{"start":1732019862.123274087905884,"finish":1732019862.123327016830444,"duration":0.000052928924561},"Save and compact":{"start":1732019862.123328924179077,"finish":1732019862.125181913375854,"duration":0.001852989196777},"Detect loops":{"start":1732019862.125188112258911,"finish":1732019862.125616073608398,"duration":0.000427961349487},"Apply Dependency Management":{"start":1732019862.125618934631348,"finish":1732019862.126152038574219,"duration":0.000533103942871},"Save Dependency management cache":{"start":1732019862.126036882400513,"finish":1732019862.126149892807007,"duration":0.000113010406494},"Fill package outputs":{"start":1732019862.126187086105347,"finish":1732019862.12618899345398,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732019862.126193046569824,"finish":1732019862.126327991485596,"duration":0.000134944915771},"Find missing peerdirs":{"start":1732019862.126347064971924,"finish":1732019862.126977920532227,"duration":0.000630855560303},"Export JSON":{"start":1732019862.127032041549683,"finish":1732019862.137936115264893,"duration":0.01090407371521},"Write JSON":{"start":1732019862.127036094665527,"finish":1732019862.137914896011353,"duration":0.010878801345825},"Visit JSON":{"start":1732019862.127043962478638,"finish":1732019862.130053997039795,"duration":0.003010034561157},"Render JSON":{"start":1732019862.130063056945801,"finish":1732019862.137083053588867,"duration":0.007019996643066},"Save Uids cache":{"start":1732019862.137087106704712,"finish":1732019862.137815952301025,"duration":0.000728845596313}},"metrics":{"File access":{"loaded size":9024246,"load time":8664,"loaded MD5 time":737,"Max loaded MD5 time":5,"loaded count":2174,"max load time":18,"mapped size":40632376,"mapped MD5 time":5807,"Max mapped MD5 time":362,"mapped count":130,"map time":636,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3085,"lstat min us":0,"lstat avr us":1,"lstat max us":16,"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":1812,"readdir min us":0,"readdir avr us":0,"readdir max us":36,"(opendir + readdir) sum us":1812,"(lstat + opendir + readdir) sum us":4897},"Parsing":{"parse time":3,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/yatmpuu01v_1g","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-80332a3dd2fbf236732d73bb012dec23_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.bfw74f97/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.42598295211792} 2024-11-19 12:37:42,147 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732019862.1475837 2024-11-19 12:37:42,147 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732019862.1475837 2024-11-19 12:37:42,147 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732019862.1475837 2024-11-19 12:37:42,147 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732019862.1477082 2024-11-19 12:37:42,147 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732019862.1477082 2024-11-19 12:37:42,147 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732019862.1477082 2024-11-19 12:37:42,147 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019862.1479788 2024-11-19 12:37:42,148 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished=1732019862.1479788 2024-11-19 12:37:42,148 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-pic_finished - 1732019862.1479788 2024-11-19 12:37:42,148 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019862.1482387 2024-11-19 12:37:42,148 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_started=1732019862.1482387 2024-11-19 12:37:42,148 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_started - 1732019862.1482387 2024-11-19 12:37:42,148 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732019862.1483958 2024-11-19 12:37:42,148 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732019862.1483958 2024-11-19 12:37:42,148 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732019862.1483958 2024-11-19 12:37:42,148 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019862.148635 2024-11-19 12:37:42,148 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished=1732019862.148635 2024-11-19 12:37:42,148 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732019862.148635 2024-11-19 12:37:42,148 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019862.148875 2024-11-19 12:37:42,148 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started=1732019862.148875 2024-11-19 12:37:42,148 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_started - 1732019862.148875 2024-11-19 12:37:42,152 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-19 12:37:42,153 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-19 12:37:42,153 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.0003464221954345703 2024-11-19 12:37:42,153 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.0002224445343017578 2024-11-19 12:37:42,153 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019862.1533985 2024-11-19 12:37:42,153 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished=1732019862.1533985 2024-11-19 12:37:42,153 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-pic_finished - 1732019862.1533985 2024-11-19 12:37:42,153 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019862.1534505 2024-11-19 12:37:42,153 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished=1732019862.1534505 2024-11-19 12:37:42,153 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732019862.1534505 2024-11-19 12:37:42,153 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-pic, group=graph, time=1732019862.1538801 2024-11-19 12:37:42,153 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished=1732019862.1538801 2024-11-19 12:37:42,154 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-pic_finished - 1732019862.1538801 2024-11-19 12:37:42,154 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-relwithdebinfo-nopic, group=graph, time=1732019862.1542454 2024-11-19 12:37:42,154 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished=1732019862.1542454 2024-11-19 12:37:42,154 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-relwithdebinfo-nopic_finished - 1732019862.1542454 2024-11-19 12:37:42,154 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-relwithdebinfo: 0.025457382202148438 2024-11-19 12:37:42,154 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732019862.154646 2024-11-19 12:37:42,154 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732019862.154646 2024-11-19 12:37:42,154 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732019862.154646 2024-11-19 12:37:42,154 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732019862.1548157 2024-11-19 12:37:42,154 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732019862.1548157 2024-11-19 12:37:42,154 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732019862.1548157 2024-11-19 12:37:42,155 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-relwithdebinfo: 0.0004265308380126953 2024-11-19 12:37:42,155 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732019862.1550379 2024-11-19 12:37:42,155 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732019862.1550379 2024-11-19 12:37:42,155 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732019862.1550379 2024-11-19 12:37:42,155 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732019862.155172 2024-11-19 12:37:42,155 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732019862.155172 2024-11-19 12:37:42,155 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732019862.155172 2024-11-19 12:37:42,155 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-relwithdebinfo: 0.0003216266632080078 2024-11-19 12:37:42,155 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-19 12:37:42,155 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732019862.1554024 2024-11-19 12:37:42,155 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732019862.1554024 2024-11-19 12:37:42,155 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732019862.1554024 2024-11-19 12:37:42,155 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732019862.1555414 2024-11-19 12:37:42,155 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732019862.1555414 2024-11-19 12:37:42,155 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732019862.1555414 2024-11-19 12:37:42,155 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-relwithdebinfo: 0.00034427642822265625 2024-11-19 12:37:42,155 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-19 12:37:42,155 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732019862.155738 2024-11-19 12:37:42,155 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732019862.155738 2024-11-19 12:37:42,155 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732019862.155738 2024-11-19 12:37:42,155 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-19 12:37:42,156 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732019862.15626 2024-11-19 12:37:42,156 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732019862.15626 2024-11-19 12:37:42,156 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732019862.15626 2024-11-19 12:37:42,156 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732019862.156671 2024-11-19 12:37:42,156 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732019862.156671 2024-11-19 12:37:42,156 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732019862.156671 2024-11-19 12:37:42,156 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-19 12:37:42,170 DEBUG (build.genconf) [Misc-04] Generating conf into /home/runner/.ya/build/conf/dist-relwithdebinfo-c0348968119373774c0780f660d06e8f/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-relwithdebinfo', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZvcm11bGEiOnsiYnlfcGxhdGZvcm0iOnsiZGFyd2luLWFybTY0Ijp7InVyaSI6InNicjo3NDUxNjAyNjA5In0sImRhcndpbi14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE1ODU5NjIifSwibGludXgtYWFyY2g2NCI6eyJ1cmkiOiJzYnI6NzQ1MTcxMjMzMiJ9LCJsaW51eC14ODZfNjQiOnsidXJpIjoic2JyOjc0NTE0NjY0MDcifSwid2luMzIteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDA2OTAwIn19fSwibmFtZSI6ImNsYW5nMTgiLCJwYXJhbXMiOnsiY19jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nIiwiY3h4X2NvbXBpbGVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vY2xhbmcrKyIsImdjY192ZXJzaW9uIjoiMTgiLCJsbHZtLXN5bWJvbGl6ZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLXN5bWJvbGl6ZXIiLCJtYXRjaF9yb290IjoiQ0xBTkctMTczNTA1NjgyMSIsIm9iamNvcHkiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9sbHZtLW9iamNvcHkiLCJwcm9maWxlcyI6IiQoWENPREVfVE9PTFNfUk9PVC1zYnI6Nzk5MDE3NzcxKS9YY29kZS9Db250ZW50cy9EZXZlbG9wZXIvUGxhdGZvcm1zL2lQaG9uZU9TLnBsYXRmb3JtL0RldmVsb3Blci9MaWJyYXJ5L0NvcmVTaW11bGF0b3IvUHJvZmlsZXMiLCJzaW1jdGwiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvU3lzdGVtUm9vdC9Qcml2YXRlRnJhbWV3b3Jrcy9Db3JlU2ltdWxhdG9yLmZyYW1ld29yay9SZXNvdXJjZXMvYmluL3NpbWN0bCIsInN0cmlwIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zdHJpcCIsInR5cGUiOiJjbGFuZyIsInVzZV9idW5kbGUiOnRydWUsIndlcnJvcl9tb2RlIjoiYWxsIn0sInBsYXRmb3JtIjp7Imhvc3QiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9LCJ0YXJnZXQiOnsiYXJjaCI6Ing4Nl82NCIsIm9zIjoiTElOVVgiLCJ0b29sY2hhaW4iOiJkZWZhdWx0IiwidmlzaWJsZV9uYW1lIjoiY2xhbmcxOCJ9fSwicGxhdGZvcm1fbmFtZSI6IkRFRkFVTFQtTElOVVgtWDg2XzY0IiwidG9vbF92YXIiOiJDTEFORy0xNzM1MDU2ODIxIn0=', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'IS_CROSS_TOOLS=yes', '-D', 'OPENSOURCE=yes', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-19 12:37:42,288 DEBUG (build.genconf) [Misc-04] Copy conf /home/runner/.ya/build/conf/dist-relwithdebinfo-c0348968119373774c0780f660d06e8f/ymake.conf to /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf 2024-11-19 12:37:42,288 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-19 12:37:42,288 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732019862.2888727 2024-11-19 12:37:42,288 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732019862.2888727 2024-11-19 12:37:42,288 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732019862.2888727 2024-11-19 12:37:42,289 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732019862.2890522 2024-11-19 12:37:42,289 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732019862.2890522 2024-11-19 12:37:42,289 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732019862.2890522 2024-11-19 12:37:42,289 DEBUG (build.ymake2) [Misc-04] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'test_tool_tc1-nopic-global', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19', 'build_type': 'relwithdebinfo', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3', '/home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f071da63880>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/makefiles.dart'} 2024-11-19 12:37:42,289 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-19 12:37:42,289 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-19 12:37:42,289 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-19 12:37:42,289 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-19 12:37:42,290 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/1732019861.2967.i6lqhjqw/yatmpf4joq6ji --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/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:37:42,651 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/1732019861.2967.i6lqhjqw/yatmpf4joq6ji --config /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/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:37:42,652 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-19 12:37:42,652 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732019862.28944993019104,"finish":1732019862.289993047714233,"duration":0.000543117523193},"execution":{"start":1732019862.289993524551392,"finish":1732019862.651971817016602,"duration":0.36197829246521},"postprocessing":{"start":1732019862.651974201202393,"finish":1732019862.65228009223938,"duration":0.000305891036987}},"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/1732019861.2967.i6lqhjqw/yatmpf4joq6ji","--config","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-relwithdebinfo-x-6e95ef52940bd0954316c8e7cca867ae_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/gen_graph.agjhu8uk/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.36197829246521} 2024-11-19 12:37:42,652 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732019862.652658 2024-11-19 12:37:42,652 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732019862.652658 2024-11-19 12:37:42,652 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732019862.652658 2024-11-19 12:37:42,652 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732019862.652902 2024-11-19 12:37:42,652 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732019862.652902 2024-11-19 12:37:42,652 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732019862.652902 2024-11-19 12:37:42,653 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732019862.6531122 2024-11-19 12:37:42,653 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732019862.6531122 2024-11-19 12:37:42,653 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732019862.6531122 2024-11-19 12:37:42,653 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732019862.6533763 2024-11-19 12:37:42,653 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732019862.6533763 2024-11-19 12:37:42,653 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732019862.6533763 2024-11-19 12:37:42,653 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732019862.6539655 2024-11-19 12:37:42,654 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732019862.6539655 2024-11-19 12:37:42,654 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732019862.6539655 2024-11-19 12:37:42,655 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732019862.6556532 2024-11-19 12:37:42,655 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732019862.6556532 2024-11-19 12:37:42,655 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732019862.6556532 2024-11-19 12:37:42,655 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732019862.6558604 2024-11-19 12:37:42,655 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732019862.6558604 2024-11-19 12:37:42,655 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732019862.6558604 2024-11-19 12:37:42,656 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732019862.6560156 2024-11-19 12:37:42,656 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732019862.6560156 2024-11-19 12:37:42,656 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732019862.6560156 2024-11-19 12:37:42,656 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732019862.6566727 2024-11-19 12:37:42,656 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732019862.6566727 2024-11-19 12:37:42,656 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732019862.6566727 2024-11-19 12:37:42,656 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-19 12:37:42,656 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f071db19ee0>' on 0 suites 2024-11-19 12:37:42,656 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-19 12:37:42,657 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-19 12:37:42,657 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-19 12:37:42,657 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-19 12:37:42,657 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-19 12:37:42,657 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-relwithdebinfo: 0.0009717941284179688 2024-11-19 12:37:42,657 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-relwithdebinfo 2024-11-19 12:37:42,657 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732019862.657886 2024-11-19 12:37:42,657 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732019862.657886 2024-11-19 12:37:42,657 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732019862.657886 2024-11-19 12:37:42,658 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732019862.6580288 2024-11-19 12:37:42,658 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732019862.6580288 2024-11-19 12:37:42,658 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732019862.6580288 2024-11-19 12:37:42,658 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732019862.658149 2024-11-19 12:37:42,658 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732019862.658149 2024-11-19 12:37:42,658 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732019862.658149 2024-11-19 12:37:42,658 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-19 12:37:42,658 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:37:42,658 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-19 12:37:42,658 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-19 12:37:42,659 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732019862.6589944 2024-11-19 12:37:42,659 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732019862.6589944 2024-11-19 12:37:42,659 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732019862.6589944 2024-11-19 12:37:42,659 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732019862.6591535 2024-11-19 12:37:42,659 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732019862.6591535 2024-11-19 12:37:42,659 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732019862.6591535 2024-11-19 12:37:42,659 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732019862.6592903 2024-11-19 12:37:42,659 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732019862.6592903 2024-11-19 12:37:42,659 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732019862.6592903 2024-11-19 12:37:42,659 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.1030819416046143 2024-11-19 12:37:42,659 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732019862.6594608 2024-11-19 12:37:42,659 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732019862.6594608 2024-11-19 12:37:42,659 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732019862.6594608 2024-11-19 12:37:42,659 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-19 12:37:42,659 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00022721290588378906 2024-11-19 12:37:42,659 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732019862.6596804 2024-11-19 12:37:42,659 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732019862.6596804 2024-11-19 12:37:42,659 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732019862.6596804 2024-11-19 12:37:42,659 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.0001888275146484375 2024-11-19 12:37:42,659 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-19 12:37:42,660 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732019862.660103 2024-11-19 12:37:42,660 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732019862.660103 2024-11-19 12:37:42,660 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732019862.660103 2024-11-19 12:37:42,660 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-19 12:37:42,660 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 8.344650268554688e-06 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732019862.6603231 2024-11-19 12:37:42,660 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732019862.6603231 2024-11-19 12:37:42,660 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732019862.6603231 2024-11-19 12:37:42,660 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006234645843505859 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732019862.660497 2024-11-19 12:37:42,660 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732019862.660497 2024-11-19 12:37:42,660 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732019862.660497 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732019862.6606064 2024-11-19 12:37:42,660 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732019862.6606064 2024-11-19 12:37:42,660 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732019862.6606064 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732019862.6607294 2024-11-19 12:37:42,660 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732019862.6607294 2024-11-19 12:37:42,660 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732019862.6607294 2024-11-19 12:37:42,660 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:37:42,660 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:37:42,660 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-19 12:37:42,660 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732019862.6609876 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732019862.6609876 2024-11-19 12:37:42,661 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732019862.6609876 2024-11-19 12:37:42,661 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732019862.661144 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732019862.661144 2024-11-19 12:37:42,661 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732019862.661144 2024-11-19 12:37:42,661 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732019862.6614165 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732019862.6614165 2024-11-19 12:37:42,661 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732019862.6614165 2024-11-19 12:37:42,661 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732019862.6615536 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732019862.6615536 2024-11-19 12:37:42,661 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732019862.6615536 2024-11-19 12:37:42,661 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0012412071228027344 2024-11-19 12:37:42,661 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732019862.6617372 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732019862.6617372 2024-11-19 12:37:42,661 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732019862.6617372 2024-11-19 12:37:42,661 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732019862.6619353 2024-11-19 12:37:42,661 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732019862.6619353 2024-11-19 12:37:42,662 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732019862.6619353 2024-11-19 12:37:42,662 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732019862.6622188 2024-11-19 12:37:42,662 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732019862.6622188 2024-11-19 12:37:42,662 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732019862.6622188 2024-11-19 12:37:42,662 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732019862.662363 2024-11-19 12:37:42,662 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732019862.662363 2024-11-19 12:37:42,662 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732019862.662363 2024-11-19 12:37:42,662 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732019862.6625426 2024-11-19 12:37:42,662 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732019862.6625426 2024-11-19 12:37:42,662 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732019862.6625426 2024-11-19 12:37:42,662 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732019862.6626701 2024-11-19 12:37:42,662 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732019862.6626701 2024-11-19 12:37:42,662 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732019862.6626701 2024-11-19 12:37:42,662 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.1366667747497559 2024-11-19 12:37:42,664 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732019862.6642761 2024-11-19 12:37:42,664 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732019862.6642761 2024-11-19 12:37:42,664 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732019862.6642761 2024-11-19 12:37:42,664 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-19 12:37:42,664 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-19 12:37:42,664 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-19 12:37:42,664 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732019862.6645515 2024-11-19 12:37:42,664 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732019862.6645515 2024-11-19 12:37:42,664 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732019862.6645515 2024-11-19 12:37:42,664 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732019862.6646924 2024-11-19 12:37:42,664 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732019862.6646924 2024-11-19 12:37:42,664 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732019862.6646924 2024-11-19 12:37:42,664 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-19 12:37:42,664 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732019862.664896 2024-11-19 12:37:42,664 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732019862.664896 2024-11-19 12:37:42,664 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732019862.664896 2024-11-19 12:37:42,665 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-19 12:37:42,666 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-19 12:37:42,667 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-19 12:37:42,668 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-19 12:37:42,669 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-19 12:37:42,670 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-19 12:37:42,671 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-19 12:37:42,672 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-19 12:37:42,673 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-19 12:37:42,674 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-19 12:37:42,675 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-19 12:37:42,677 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-19 12:37:42,678 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-19 12:37:42,679 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-19 12:37:42,684 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/50473277703254653844527676656f477a75346d4d6700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,684 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing PG2wp2Te8DRvveoGzu4mMg => True 2024-11-19 12:37:42,685 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/3162744e6561686b67364c626b4f763877436361375100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,685 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/6b557878797163536c314878574c32517a36517a427700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,685 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 1btNeahkg6LbkOv8wCca7Q => True 2024-11-19 12:37:42,686 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing kUxxyqcSl1HxWL2Qz6QzBw => True 2024-11-19 12:37:42,686 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,686 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/734f36312d416949456f5552695374324239584d385100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,687 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,687 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/544c30434f50634f35626e48517959314431764d774100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,687 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-19 12:37:42,688 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing sO61-AiIEoURiSt2B9XM8Q => True 2024-11-19 12:37:42,688 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-19 12:37:42,688 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing TL0COPcO5bnHQyY1D1vMwA => True 2024-11-19 12:37:42,689 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/5662364254516f71785a32646c6c477932683455546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,689 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing Vb6BTQoqxZ2dllGy2h4UTg => False 2024-11-19 12:37:42,691 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/5a5435547a58554e7a41625252323252752d5261766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,691 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing ZT5TzXUNzAbRR22Ru-Ravg => False 2024-11-19 12:37:42,692 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/637a52574c69497847757767574b5458373044342d7700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,692 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/634f6342476a35626766706c5554397330754c746e5100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,693 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:42,693 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7733414239525155626962393066627532414d6b304100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,693 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing czRWLiIxGuwgWKTX70D4-w => True 2024-11-19 12:37:42,693 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing cOcBGj5bgfplUT9s0uLtnQ => True 2024-11-19 12:37:42,693 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-19 12:37:42,694 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing w3AB9RQUbib90fbu2AMk0A => False 2024-11-19 12:37:42,694 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-19 12:37:42,694 DEBUG (build.graph) [MainThread] stripped 10, left 3 nodes 2024-11-19 12:37:42,698 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732019862.6987023 2024-11-19 12:37:42,698 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732019862.6987023 2024-11-19 12:37:42,698 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732019862.6987023 2024-11-19 12:37:42,698 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.035965681076049805 2024-11-19 12:37:42,699 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732019862.6991494 2024-11-19 12:37:42,699 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732019862.6991494 2024-11-19 12:37:42,699 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732019862.6991494 2024-11-19 12:37:42,699 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732019862.699342 2024-11-19 12:37:42,699 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732019862.699342 2024-11-19 12:37:42,699 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732019862.699342 2024-11-19 12:37:42,699 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:37:42,699 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:37:42,699 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:37:42,699 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:37:42,699 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:37:42,699 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:37:42,700 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:37:42,700 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:37:42,700 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:37:42,700 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 12:37:42,700 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:37:42,700 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:37:42,700 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:37:42,701 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:37:42,701 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:37:42,701 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:37:42,701 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:37:42,701 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-19 12:37:42,701 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-19 12:37:42,701 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732019862.701231 2024-11-19 12:37:42,701 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732019862.701231 2024-11-19 12:37:42,701 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732019862.701231 2024-11-19 12:37:42,701 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-19 12:37:42,712 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732019861.2967.i6lqhjqw/2967al_lxfog', '0'] 2024-11-19 12:37:42,712 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-19 12:37:42,734 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/7hx0/STAMP 2024-11-19 12:37:42,734 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.075599670410156e-05 2024-11-19 12:37:42,734 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.0001049041748046875 2024-11-19 12:37:42,734 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 5.555152893066406e-05 2024-11-19 12:37:42,734 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 5.030632019042969e-05 2024-11-19 12:37:42,735 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-19 12:37:42,840 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-19 12:37:42,844 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid ZT5TzXUNzAbRR22Ru-Ravg) address: (2549, 19742, 'unix:/home/runner/.ya/build/cache/7/psing9zTy08.tmp') 2024-11-19 12:37:42,851 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for Vb6BTQoqxZ2dllGy2h4UTg created (provider=None) 2024-11-19 12:37:42,852 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for ZT5TzXUNzAbRR22Ru-Ravg created (provider=None) 2024-11-19 12:37:42,852 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for w3AB9RQUbib90fbu2AMk0A created (provider=None) 2024-11-19 12:37:42,853 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-19 12:37:42,853 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-19 12:37:42,855 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/5a5435547a58554e7a41625252323252752d5261766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,855 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing ZT5TzXUNzAbRR22Ru-Ravg => False 2024-11-19 12:37:42,859 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:37:42,859 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/7733414239525155626962393066627532414d6b304100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,859 DEBUG (urllib3.connectionpool) [Worker-009] http://cachesrv.internal:8081 "HEAD /ac/5662364254516f71785a32646c6c477932683455546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:42,860 DEBUG (yalibrary.toolscache) [Worker-005] Tools cache attempt: (request for resource 7336031045) 2024-11-19 12:37:42,860 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing w3AB9RQUbib90fbu2AMk0A => False 2024-11-19 12:37:42,860 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-009] Bazel-remote Probing Vb6BTQoqxZ2dllGy2h4UTg => False 2024-11-19 12:37:42,861 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-007] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-19 12:37:42,861 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:37:42,862 DEBUG (yalibrary.toolscache) [Worker-007] Tools cache attempt: (request for resource 7458630270) 2024-11-19 12:37:42,862 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:37:42,862 DEBUG (library.python.filelock) [Worker-005] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:37:42,862 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-19 12:37:42,862 DEBUG (yalibrary.toolscache) [Worker-007] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-19 12:37:42,863 DEBUG (exts.http_client) [Worker-005] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.l2x7zx3i.ffnkv7f6.part, expect md5- 2024-11-19 12:37:42,863 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:37:42,864 DEBUG (library.python.filelock) [Worker-007] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:37:42,864 DEBUG (yalibrary.fetcher.cache_helper) [Worker-007] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-19 12:37:42,864 DEBUG (exts.http_client) [Worker-007] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.dvjzn2ww.jjgq8479.part, expect md5- 2024-11-19 12:37:42,987 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:37:42 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: e2d60897cbb5167c NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 12:37:42,989 DEBUG (exts.http_client) [Worker-007] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Tue, 19 Nov 2024 12:37:43 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: 5bf59c5d6b98cc94 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-19 12:37:43,136 DEBUG (exts.http_client) [Worker-005] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.l2x7zx3i, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.272803 2024-11-19 12:37:43,136 DEBUG (yalibrary.fetcher.common) [Worker-005] extract /home/runner/.ya/tools/v4/7336031045/resource.l2x7zx3i to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-19 12:37:43,292 DEBUG (library.python.filelock) [Worker-005] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-19 12:37:43,296 DEBUG (yalibrary.toolscache) [Worker-005] AC cache attempt: (get uid cef3d422a8b92e297156d8720a3ca671) 2024-11-19 12:37:43,303 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/a82874d48c17e5a7767299405c283d4dbfa917e4e9686e7ab048c9d27e9d5d11 HTTP/1.1" 404 0 2024-11-19 12:37:43,303 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing cef3d422a8b92e297156d8720a3ca671 => False 2024-11-19 12:37:43,304 DEBUG (root) [Worker-005] Run node ZT5TzXUNzAbRR22Ru-Ravg in build root /home/runner/.ya/build/build_root/7hx0/000006 2024-11-19 12:37:43,852 DEBUG (yalibrary.runner.tasks.result) [Worker-005] Result node ZT5TzXUNzAbRR22Ru-Ravg processed successfully: True 2024-11-19 12:37:43,854 DEBUG (yalibrary.toolscache) [Worker-026] AC cache attempt: (put uid ZT5TzXUNzAbRR22Ru-Ravg) 2024-11-19 12:37:43,856 DEBUG (yalibrary.toolscache) [Worker-026] AC cache attempt: (put uid cef3d422a8b92e297156d8720a3ca671) 2024-11-19 12:37:43,860 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/5a5435547a58554e7a41625252323252752d5261766700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:37:43,860 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing ZT5TzXUNzAbRR22Ru-Ravg => False 2024-11-19 12:37:43,861 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Put ydb/docs/__docs_internal.docslib.fake(ZT5TzXUNzAbRR22Ru-Ravg) to Bazel-remote 2024-11-19 12:37:43,872 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "PUT /cas/584e18eb654b2a844e44c04c2e09a03fa7c44777d8ff24906da5ee594db50ecd HTTP/1.1" 200 0 2024-11-19 12:37:43,881 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "PUT /cas/1a116056dd4b18e2d7b41e94b1fd87ed5f60073d83a2b82af524bf9c356568c7 HTTP/1.1" 200 0 2024-11-19 12:37:44,411 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "PUT /cas/d173c1d0e93546f24d0dfc624ba3adae63a3a1bab4bfefa8ae42e53d80569b21 HTTP/1.1" 200 0 2024-11-19 12:37:44,421 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "PUT /ac/5a5435547a58554e7a41625252323252752d5261766700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:37:44,421 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Put ydb/docs/__docs_internal.docslib.fake(ZT5TzXUNzAbRR22Ru-Ravg) size=51589393 to Bazel-remote completed 2024-11-19 12:37:51,063 DEBUG (exts.http_client) [Worker-007] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.dvjzn2ww, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=8.199112 2024-11-19 12:37:51,063 DEBUG (yalibrary.fetcher.common) [Worker-007] extract /home/runner/.ya/tools/v4/7458630270/resource.dvjzn2ww to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-19 12:37:51,398 DEBUG (library.python.filelock) [Worker-007] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-19 12:37:51,399 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (get uid 1852b629dd7a78fe118db81790ca64a7) 2024-11-19 12:37:51,406 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/d558338eff748ab8108e69d9343b0633794ece7090c8037edb34bf97b3cf6f11 HTTP/1.1" 404 0 2024-11-19 12:37:51,407 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing 1852b629dd7a78fe118db81790ca64a7 => False 2024-11-19 12:37:51,442 DEBUG (root) [Worker-007] Run node Vb6BTQoqxZ2dllGy2h4UTg in build root /home/runner/.ya/build/build_root/7hx0/000008 2024-11-19 12:38:10,322 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (get uid cfaa9bd5942815e8a55c6b25230ca143) 2024-11-19 12:38:10,323 DEBUG (yalibrary.toolscache) [Worker-047] AC cache attempt: (put uid Vb6BTQoqxZ2dllGy2h4UTg) 2024-11-19 12:38:10,324 DEBUG (yalibrary.toolscache) [Worker-047] AC cache attempt: (put uid 1852b629dd7a78fe118db81790ca64a7) 2024-11-19 12:38:10,325 DEBUG (yalibrary.runner.tasks.result) [Worker-042] Result node Vb6BTQoqxZ2dllGy2h4UTg processed successfully: True 2024-11-19 12:38:10,329 DEBUG (urllib3.connectionpool) [Worker-041] http://cachesrv.internal:8081 "HEAD /ac/5662364254516f71785a32646c6c477932683455546700000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:38:10,329 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-041] Bazel-remote Probing Vb6BTQoqxZ2dllGy2h4UTg => False 2024-11-19 12:38:10,329 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-041] Put ydb/docs/preprocessed.tar.gz(Vb6BTQoqxZ2dllGy2h4UTg) to Bazel-remote 2024-11-19 12:38:10,330 DEBUG (urllib3.connectionpool) [Worker-043] http://cachesrv.internal:8081 "HEAD /ac/cb6d7a9f41dd7c3cba1b469ffefb4d8cf08a7a6d22f7114b7678a2a15537c811 HTTP/1.1" 404 0 2024-11-19 12:38:10,331 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-043] Bazel-remote Probing cfaa9bd5942815e8a55c6b25230ca143 => False 2024-11-19 12:38:10,331 DEBUG (root) [Worker-043] Run node w3AB9RQUbib90fbu2AMk0A in build root /home/runner/.ya/build/build_root/7hx0/000007 2024-11-19 12:38:10,338 DEBUG (urllib3.connectionpool) [Worker-041] http://cachesrv.internal:8081 "PUT /cas/e428455f3af4c71c5dfcf5274d09690722c303e85ae5cf91c8d7d0750adab8dc HTTP/1.1" 200 0 2024-11-19 12:38:10,346 DEBUG (urllib3.connectionpool) [Worker-041] http://cachesrv.internal:8081 "PUT /cas/f0cef17574ff83a6052623fe527b0d3c252b32f4fc2891c0790bb73ad0130f1a HTTP/1.1" 200 0 2024-11-19 12:38:10,736 DEBUG (urllib3.connectionpool) [Worker-041] http://cachesrv.internal:8081 "PUT /cas/3a1a7eba0e01c4e8a58b866cbdf96b505a8bfbc3551674cafbe69acb94c1ced0 HTTP/1.1" 200 0 2024-11-19 12:38:10,744 DEBUG (urllib3.connectionpool) [Worker-041] http://cachesrv.internal:8081 "PUT /ac/5662364254516f71785a32646c6c477932683455546700000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:38:10,744 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-041] Put ydb/docs/preprocessed.tar.gz(Vb6BTQoqxZ2dllGy2h4UTg) size=36209200 to Bazel-remote completed 2024-11-19 12:38:54,152 DEBUG (yalibrary.toolscache) [Worker-056] AC cache attempt: (put uid w3AB9RQUbib90fbu2AMk0A) 2024-11-19 12:38:54,153 DEBUG (yalibrary.runner.tasks.result) [Worker-043] Result node w3AB9RQUbib90fbu2AMk0A processed successfully: True 2024-11-19 12:38:54,154 DEBUG (yalibrary.toolscache) [Worker-056] AC cache attempt: (put uid cfaa9bd5942815e8a55c6b25230ca143) 2024-11-19 12:38:54,158 DEBUG (urllib3.connectionpool) [Worker-057] http://cachesrv.internal:8081 "HEAD /ac/7733414239525155626962393066627532414d6b304100000000000000000001 HTTP/1.1" 404 0 2024-11-19 12:38:54,159 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-057] Bazel-remote Probing w3AB9RQUbib90fbu2AMk0A => False 2024-11-19 12:38:54,159 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-057] Put ydb/docs/ydb-docs.tar.gz(w3AB9RQUbib90fbu2AMk0A) to Bazel-remote 2024-11-19 12:38:54,168 DEBUG (urllib3.connectionpool) [Worker-057] http://cachesrv.internal:8081 "PUT /cas/f06cde8d8234bee4ff7f327a49afd931b1330b8c97bdd9c883a8a2c2be064777 HTTP/1.1" 200 0 2024-11-19 12:38:54,176 DEBUG (urllib3.connectionpool) [Worker-057] http://cachesrv.internal:8081 "PUT /cas/b4417124ac8ea3b7bf0a6211d43eab0afc1e3261fa50ec2b93f31946b3cd6326 HTTP/1.1" 200 0 2024-11-19 12:38:54,587 DEBUG (urllib3.connectionpool) [Worker-057] http://cachesrv.internal:8081 "PUT /cas/aa79b1104a5935be45910f4b229d2ac0291e966189b91359c3e0d6b89e6f5452 HTTP/1.1" 200 0 2024-11-19 12:38:54,596 DEBUG (urllib3.connectionpool) [Worker-057] http://cachesrv.internal:8081 "PUT /ac/7733414239525155626962393066627532414d6b304100000000000000000001 HTTP/1.1" 200 0 2024-11-19 12:38:54,596 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-057] Put ydb/docs/ydb-docs.tar.gz(w3AB9RQUbib90fbu2AMk0A) size=39680486 to Bazel-remote completed 2024-11-19 12:38:54,656 DEBUG (yalibrary.runner.build_root) [Worker-069] Build root /home/runner/.ya/build/build_root/7hx0 created=8 left=5 2024-11-19 12:38:54,656 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/7hx0/STAMP 2024-11-19 12:38:54,664 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(Vb6BTQoqxZ2dllGy2h4UTg$(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:38:54,664 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(w3AB9RQUbib90fbu2AMk0A$(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:38:54,665 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-19 12:38:54,665 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.002309083938599,"qty":1},"prepare_all_nodes":{"sum":0.012331485748291,"qty":1},"compact_cache":{"sum":0.001008033752441,"qty":1},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.433413028717041,"qty":1},"DO":{"sum":63.310723066329956,"qty":3},"restore_from_dist_cache[DO]":{"sum":0.032207012176514,"qty":3},"result[DO]":{"sum":0.005839586257935,"qty":3},"put_in_cache[DO]":{"sum":0.008234024047852,"qty":3},"write_through_caches[DO]":{"sum":0.022845268249512,"qty":3},"put_in_dist_cache[DO]":{"sum":1.542645215988159,"qty":3},"pattern[YFM_TOOL-2315971726]":{"sum":8.537126779556274,"qty":1},"clean_build_root":{"sum":0.000249624252319,"qty":1}},"all":{"qty":24,"sum":73.908932209014893},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732019862.861432552337646,1732019871.398559331893921],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":71.824655294418335,"build_type":"relwithdebinfo","flags":{"OPENSOURCE":"yes","USE_PREBUILT_TOOLS":"no","APPLE_SDK_LOCAL":"yes","USE_CLANG_CL":"yes","USE_AIO":"static","USE_ICONV":"static","USE_IDN":"static","CFLAGS":"-fno-omit-frame-pointer","USE_EAT_MY_DATA":"yes","DEBUGINFO_LINES_ONLY":"yes","CONSISTENT_DEBUG":"yes","TESTS_REQUESTED":"yes","DISABLE_FLAKE8_MIGRATIONS":"yes"},"rel_targets":["."],"threads":64} 2024-11-19 12:38:54,665 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-19 12:38:54,665 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732019934.6654475 2024-11-19 12:38:54,665 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732019934.6654475 2024-11-19 12:38:54,665 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732019934.6654475 2024-11-19 12:38:54,665 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732019934.6657195 2024-11-19 12:38:54,665 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732019934.6657195 2024-11-19 12:38:54,665 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732019934.6657195 2024-11-19 12:38:54,679 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-19 12:38:54,679 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-19 12:38:54,679 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,679 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,679 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-19 12:38:54,679 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-19 12:38:54,680 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-19 12:38:54,681 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-19 12:38:54,689 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,689 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,690 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,690 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-19 12:38:54,690 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732019934.690885 2024-11-19 12:38:54,690 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732019934.690885 2024-11-19 12:38:54,690 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732019934.690885 2024-11-19 12:38:54,691 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-19 12:38:54,691 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-19 12:38:54,691 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-19 12:38:54,692 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 127508480 TotalSize: 127479079 2024-11-19 12:38:54,692 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732019934.6924694 2024-11-19 12:38:54,692 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732019934.6924694 2024-11-19 12:38:54,692 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732019934.6924694 2024-11-19 12:38:54,692 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 26. 2024-11-19 12:38:54,692 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 2. 2024-11-19 12:38:54,693 DEBUG (build.stat.statistics) [MainThread] Run tasks 3: 3 cached tasks (0 cache(s) resolved by dynamic uids), 3 not cached, 0 failed 2024-11-19 12:38:54,693 DEBUG (build.stat.statistics) [MainThread] Not cached 3: 0 tests, 3 not tests 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 50.0 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 3 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 6 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 3 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 3 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 3 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -3 2024-11-19 12:38:54,693 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",538,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",18858,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",43799,"local"]] 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 70826 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 63195 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 63195 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-19 12:38:54,694 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 43799 2024-11-19 12:38:54,695 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-19 12:38:54,695 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-19 12:38:54,695 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-19 12:38:54,695 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-19 12:38:54,695 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 63195 2024-11-19 12:38:54,695 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732019934.695883 2024-11-19 12:38:54,695 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732019934.695883 2024-11-19 12:38:54,696 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732019934.695883 2024-11-19 12:38:54,696 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732019934.6964555 2024-11-19 12:38:54,696 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732019934.6964555 2024-11-19 12:38:54,696 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732019934.6964555 2024-11-19 12:38:54,696 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-19 12:38:54,696 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 0 entries 2024-11-19 12:38:54,696 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-19 12:38:54,696 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-19 12:38:54,696 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-19 12:38:54,696 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:38:54,696 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:38:54,696 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:38:54,696 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish report 2024-11-19 12:38:54,697 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 3.218650817871094e-05}, 'BuildResultsListener': {'duration_sec': 0.00041174888610839844}} 2024-11-19 12:38:54,697 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-19 12:38:54,697 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:38:54,697 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-19 12:38:54,697 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-19 12:38:54,697 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-19 12:38:54,698 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-19 12:38:54,698 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-19 12:38:54,698 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:38:54,698 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732019934.6987994 2024-11-19 12:38:54,698 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732019934.6987994 2024-11-19 12:38:54,698 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732019934.6987994 2024-11-19 12:38:54,699 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-19 12:38:54,699 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732019934.6995296 2024-11-19 12:38:54,699 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732019934.6995296 2024-11-19 12:38:54,699 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732019934.6995296 2024-11-19 12:38:54,699 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732019934.6997175 2024-11-19 12:38:54,699 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732019934.6997175 2024-11-19 12:38:54,699 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732019934.6997175 2024-11-19 12:38:54,700 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732019934.7002294 2024-11-19 12:38:54,700 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732019934.7002294 2024-11-19 12:38:54,700 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732019934.7002294 2024-11-19 12:38:54,700 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732019934.700392 2024-11-19 12:38:54,700 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732019934.700392 2024-11-19 12:38:54,700 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732019934.700392 2024-11-19 12:38:54,700 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732019934.700568 2024-11-19 12:38:54,700 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732019934.700568 2024-11-19 12:38:54,700 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732019934.700568 2024-11-19 12:38:54,700 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000080 2024-11-19 12:38:54,700 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000027 2024-11-19 12:38:54,700 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000017 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000009 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000002 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000005 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000007 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000012 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000008 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000001 2024-11-19 12:38:54,701 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000003 2024-11-19 12:38:54,702 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:38:54,702 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732019861.2967.i6lqhjqw 2024-11-19 12:38:54,702 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-19 12:38:54,702 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000578 2024-11-19 12:38:54,702 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-19 12:38:54,702 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000005 2024-11-19 12:38:54,702 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000005 2024-11-19 12:38:54,702 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000007 2024-11-19 12:38:54,703 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.000993 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000007 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000004 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000005 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000011 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000005 2024-11-19 12:38:54,704 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])