2024-11-20 08:06:40,025 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-20 08:06:40,026 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-20 08:06:40,026 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000856 2024-11-20 08:06:40,026 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 08:06:40,026 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:06:40,026 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:06:40,026 DEBUG (root) [MainThread] vcs type: git 2024-11-20 08:06:40,026 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000230 2024-11-20 08:06:40,026 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000458 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-20 08:06:40,027 DEBUG (root) [MainThread] Start up timestamp 2024-11-20T08:06:40.UTC (2024-11-20T08:06:40.UTC) 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000041 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000006 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000429 2024-11-20 08:06:40,027 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732090000.13089.pdkghe6p instead of None 2024-11-20 08:06:40,027 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000145 2024-11-20 08:06:40,028 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732090000.0280023 2024-11-20 08:06:40,028 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732090000.0280023 2024-11-20 08:06:40,028 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732090000.0280023 2024-11-20 08:06:40,028 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732090000.0285387 2024-11-20 08:06:40,028 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732090000.0285387 2024-11-20 08:06:40,028 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732090000.0285387 2024-11-20 08:06:40,028 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-20 08:06:40,041 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-20 08:06:40,042 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-20 08:06:40,042 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-20 08:06:40,042 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 08:06:40,043 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-20 08:06:40,043 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-20 08:06:40,043 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-20 08:06:40,044 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-20 08:06:40,044 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf` with `9` keys 2024-11-20 08:06:40,044 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 08:06:40,044 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-20 08:06:40,056 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-20 08:06:40,056 DEBUG (core.common_opts) [MainThread] Could not read .ya_token file at /home/runner/.ya_token: [Errno 2] No such file or directory: '/home/runner/.ya_token' 2024-11-20 08:06:40,057 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732090000.0577877 2024-11-20 08:06:40,057 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732090000.0577877 2024-11-20 08:06:40,057 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732090000.0577877 2024-11-20 08:06:40,057 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732090000.0579386 2024-11-20 08:06:40,057 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732090000.0579386 2024-11-20 08:06:40,058 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732090000.0579386 2024-11-20 08:06:40,058 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Add [('params', ), ('hide_token', ), ('state', ), ('display', ), ('custom_file_log', ), ('display_log', ), ('vcs_type', ), ('self_info', ), ('fetcher_params', ), ('use_universal_fetcher_everywhere', ), ('docker_config_path', ), ('hide_token2', ), ('fetchers_storage', ), ('fetcher', ), ('showstack', ), ('profile', ), ('mlockall', ), ('event_queue', ), ('changelist_store', ), ('evlog', ), ('dump_debug', )] to ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir', 'params', 'hide_token', 'state', 'display']) 2024-11-20 08:06:40,058 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:06:40,058 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-20 08:06:40,058 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-20 08:06:40,058 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000326 2024-11-20 08:06:40,060 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,060 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:06:40,060 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 08:06:40,061 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,061 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 08:06:40,061 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,061 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002450 2024-11-20 08:06:40,061 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000295 2024-11-20 08:06:40,061 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000064 2024-11-20 08:06:40,062 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,062 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.001044 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000053 2024-11-20 08:06:40,063 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 08:06:40,063 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:06:40,063 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:06:40,063 DEBUG (root) [MainThread] vcs type: git 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000249 2024-11-20 08:06:40,063 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-20 08:06:40,063 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000113 2024-11-20 08:06:40,063 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000087 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-20 08:06:40,063 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000001 2024-11-20 08:06:40,064 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 08:06:40,064 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:06:40,064 DEBUG (root) [MainThread] Update replacements in 2024-11-20 08:06:40,064 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-20 08:06:40,064 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001154 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000090 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000003 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000189 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000003 2024-11-20 08:06:40,065 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000039 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-20 08:06:40,065 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000328 2024-11-20 08:06:40,066 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-20 08:06:40,066 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000516 2024-11-20 08:06:40,110 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.044201 2024-11-20 08:06:40,111 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-20 08:06:40,111 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732090000.1117313 2024-11-20 08:06:40,111 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732090000.1117313 2024-11-20 08:06:40,111 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732090000.1117313 2024-11-20 08:06:40,111 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732090000.1119256 2024-11-20 08:06:40,111 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732090000.1119256 2024-11-20 08:06:40,112 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732090000.1119256 2024-11-20 08:06:40,253 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732090000.2539358 2024-11-20 08:06:40,254 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732090000.2539358 2024-11-20 08:06:40,254 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732090000.2539358 2024-11-20 08:06:40,254 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732090000.2542257 2024-11-20 08:06:40,254 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732090000.2542257 2024-11-20 08:06:40,254 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732090000.2542257 2024-11-20 08:06:40,254 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732090000.254353 2024-11-20 08:06:40,254 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732090000.254353 2024-11-20 08:06:40,254 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732090000.254353 2024-11-20 08:06:40,254 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-20 08:06:40,254 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-20 08:06:40,254 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-20 08:06:40,254 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732090000.2545855 2024-11-20 08:06:40,254 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732090000.2545855 2024-11-20 08:06:40,254 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732090000.2545855 2024-11-20 08:06:40,254 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732090000.2548673 2024-11-20 08:06:40,254 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732090000.2548673 2024-11-20 08:06:40,254 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732090000.2548673 2024-11-20 08:06:40,257 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-20 08:06:40,257 DEBUG (yalibrary.toolscache) [MainThread] Tools cache parameters: tc enabled=True, tc master=True, ac enabled=True, ac master=True, tc_lock_file=/home/runner/.ya/tools/v4/.cache_lock, ac_lock_file=/home/runner/.ya/build/cache/7/.cache_lock, binary=None, ini=None, tc_conf={}, ac_conf={'cas_logging': 'true', 'graph_info': 'true'}, gl_conf={} 2024-11-20 08:06:40,257 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-20 08:06:40,259 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.h3uFxa7FKS' file to obtain bazel remote password 2024-11-20 08:06:40,260 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732090000.2603135 2024-11-20 08:06:40,260 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732090000.2603135 2024-11-20 08:06:40,260 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732090000.2603135 2024-11-20 08:06:40,261 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-20 08:06:40,261 DEBUG (yalibrary.vcs) [Misc-01] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: ['/home/runner/actions_runner/_work/ydb/ydb'] 2024-11-20 08:06:40,261 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 08:06:40,261 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-20 08:06:40,261 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 08:06:40,263 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-20 08:06:40,265 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-20 08:06:40,267 DEBUG (build.graph) [MainThread] flags: { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CONSISTENT_DEBUG": "yes", "DEBUGINFO_LINES_ONLY": "yes", "DISABLE_FLAKE8_MIGRATIONS": "yes", "OPENSOURCE": "yes", "TESTS_REQUESTED": "yes", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_EAT_MY_DATA": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" } 2024-11-20 08:06:40,267 DEBUG (build.graph) [MainThread] host toolchain: { "bottle_name": "clang18", "build_type": "release", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "APPLE_SDK_LOCAL": "yes", "CFLAGS": "-fno-omit-frame-pointer", "CLANG_COVERAGE": "no", "CONSISTENT_DEBUG": "yes", "NO_DEBUGINFO": "yes", "OPENSOURCE": "yes", "TIDY": "no", "TOOL_BUILD_MODE": "yes", "TRAVERSE_RECURSE": "no", "USE_AIO": "static", "USE_CLANG_CL": "yes", "USE_ICONV": "static", "USE_IDN": "static", "USE_PREBUILT_TOOLS": "no" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "tool_var": "CLANG-1735056821" } 2024-11-20 08:06:40,268 DEBUG (build.graph) [MainThread] target toolchain: { "bottle_name": "clang18", "env": { "CPATH": [ "" ], "LIBRARY_PATH": [ "" ], "SDKROOT": [ "" ] }, "executable_path": "c++", "flags": { "SANITIZER_TYPE": "address" }, "formula": { "by_platform": { "darwin-arm64": { "uri": "sbr:7451602609" }, "darwin-x86_64": { "uri": "sbr:7451585962" }, "linux-aarch64": { "uri": "sbr:7451712332" }, "linux-x86_64": { "uri": "sbr:7451466407" }, "win32-x86_64": { "uri": "sbr:7451406900" } } }, "name": "clang18", "params": { "c_compiler": "$(CLANG-1735056821)/bin/clang", "cxx_compiler": "$(CLANG-1735056821)/bin/clang++", "gcc_version": "18", "llvm-symbolizer": "$(CLANG-1735056821)/bin/llvm-symbolizer", "match_root": "CLANG-1735056821", "objcopy": "$(CLANG-1735056821)/bin/llvm-objcopy", "profiles": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles", "simctl": "$(XCODE_TOOLS_ROOT-sbr:799017771)/Xcode/SystemRoot/PrivateFrameworks/CoreSimulator.framework/Resources/bin/simctl", "strip": "$(CLANG-1735056821)/bin/llvm-strip", "type": "clang", "use_bundle": true, "werror_mode": "all" }, "platform": { "host": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" }, "target": { "arch": "x86_64", "os": "LINUX", "toolchain": "default", "visible_name": "clang18" } }, "platform_name": "DEFAULT-LINUX-X86_64", "tool_var": "CLANG-1735056821" } 2024-11-20 08:06:40,268 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-20 08:06:40,268 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-20 08:06:40,268 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: try to fetch by {'darwin': {'uri': 'sbr:7448914799'}, 'darwin-arm64': {'uri': 'sbr:7448913101'}, 'linux': {'uri': 'sbr:7448917907'}, 'linux-aarch64': {'uri': 'sbr:7448911598'}, 'win32-clang-cl': {'uri': 'sbr:7448916360'}} for 'linux' 2024-11-20 08:06:40,268 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 08:06:40,268 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 7448917907 from sbr:7448917907 to /home/runner/.ya/tools/v4/7448917907 dir, post_process=0) 2024-11-20 08:06:40,268 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-20 08:06:40,270 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-20 08:06:40,279 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-20 08:06:40,280 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-20 08:06:40,280 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 08:06:40,280 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ya-tc/public.resources.json 2024-11-20 08:06:40,280 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: try to fetch by {'darwin': {'uri': 'sbr:6812263131'}, 'darwin-arm64': {'uri': 'sbr:6812261994'}, 'linux': {'uri': 'sbr:6812260917'}, 'linux-aarch64': {'uri': 'sbr:6812259929'}} for 'linux' 2024-11-20 08:06:40,280 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-20 08:06:40,280 DEBUG (yalibrary.fetcher.resource_fetcher) [MainThread] Fetching 6812260917 from sbr:6812260917 to /home/runner/.ya/tools/v4/6812260917 dir, post_process=0) 2024-11-20 08:06:40,280 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 08:06:40,281 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-20 08:06:40,281 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-20 08:06:40,281 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 08:06:40,281 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 08:06:40,281 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters done: id=6812260917, full_path=/home/runner/.ya/tools/v4/6812260917/ya-tc 2024-11-20 08:06:40,285 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (12513, 105294, 'unix:/home/runner/.ya/tools/v4/psingRMIC6C.tmp') 2024-11-20 08:06:40,290 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:06:40,290 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-20 08:06:40,290 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-20 08:06:40,291 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.02287745475769043 2024-11-20 08:06:40,291 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.2916496 2024-11-20 08:06:40,292 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_started=1732090000.2916496 2024-11-20 08:06:40,292 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_started - 1732090000.2916496 2024-11-20 08:06:40,292 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.292106 2024-11-20 08:06:40,292 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_started=1732090000.292106 2024-11-20 08:06:40,292 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_started - 1732090000.292106 2024-11-20 08:06:40,293 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732090000.2923024 2024-11-20 08:06:40,293 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732090000.2923024 2024-11-20 08:06:40,293 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732090000.2923024 2024-11-20 08:06:40,293 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.292996 2024-11-20 08:06:40,293 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started=1732090000.292996 2024-11-20 08:06:40,293 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started - 1732090000.292996 2024-11-20 08:06:40,293 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-20 08:06:40,294 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.293163 2024-11-20 08:06:40,294 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started=1732090000.293163 2024-11-20 08:06:40,294 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started - 1732090000.293163 2024-11-20 08:06:40,294 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-20 08:06:40,294 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732090000.293497 2024-11-20 08:06:40,294 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732090000.293497 2024-11-20 08:06:40,294 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732090000.293497 2024-11-20 08:06:40,295 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --tags HEAD failed with exit code 128 2024-11-20 08:06:40,297 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-20 08:06:40,300 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'8d0bd2db4e2d31b3e765e9ba904f22d760b36726', author:b's-sabitova ', summary:b'Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0', svn_id:b'' 2024-11-20 08:06:40,301 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-carpklt5fm Host information: Linux ghrun-carpklt5fm 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-20 08:06:40,301 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"8d0bd2db4e2d31b3e765e9ba904f22d760b36726\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"s-sabitova \",\n \"ARCADIA_SOURCE_LAST_CHANGE\": -1,\n \"ARCADIA_SOURCE_PATH\": \"/home/runner/actions_runner/_work/ydb/ydb\",\n \"ARCADIA_SOURCE_REVISION\": -1,\n \"ARCADIA_SOURCE_URL\": \"\",\n \"ARCADIA_TAG\": \"\",\n \"BRANCH\": \"\",\n \"BUILD_DATE\": \"2024-11-20T08:06:40.000000Z\",\n \"BUILD_HOST\": \"ghrun-carpklt5fm\",\n \"BUILD_TIMESTAMP\": 1732090000,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: 8d0bd2db4e2d31b3e765e9ba904f22d760b36726\\n Branch: \\n Author: s-sabitova \\n Summary: Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\n\\nOther info:\\n Build by: runner\\n Top src dir: /home/runner/actions_runner/_work/ydb/ydb\\n Top build dir: /home/runner/.ya/build\\n Hostname: ghrun-carpklt5fm\\n Host information: \\n Linux ghrun-carpklt5fm 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64\\n\\n \",\n \"SCM_DATA\": \"Git info:\\n Commit: 8d0bd2db4e2d31b3e765e9ba904f22d760b36726\\n Branch: \\n Author: s-sabitova \\n Summary: Merge 756d50589a0388168ffadd3861d212d5879e8825 into 5b45c7497248b94d579ecaf8c43cdb997e1e66c0\\n\",\n \"VCS\": \"git\"\n}" 2024-11-20 08:06:40,301 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.04023933410644531 2024-11-20 08:06:40,331 DEBUG (build.genconf) [Misc-02] Generating conf into /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-20 08:06:40,333 DEBUG (build.genconf) [Misc-03] Generating conf into /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'CONSISTENT_DEBUG=yes', '-D', 'DEBUGINFO_LINES_ONLY=yes', '-D', 'DISABLE_FLAKE8_MIGRATIONS=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_EAT_MY_DATA=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-20 08:06:40,483 DEBUG (build.genconf) [Misc-03] Copy conf /home/runner/.ya/build/conf/dist-release-f3f3c5ea54295418c5f929aa2d708bb5/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf 2024-11-20 08:06:40,483 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-20 08:06:40,483 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.4837208 2024-11-20 08:06:40,483 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished=1732090000.4837208 2024-11-20 08:06:40,483 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished - 1732090000.4837208 2024-11-20 08:06:40,483 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.4839463 2024-11-20 08:06:40,484 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_started=1732090000.4839463 2024-11-20 08:06:40,484 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_started - 1732090000.4839463 2024-11-20 08:06:40,484 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-nopic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f14066a31a0>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/makefiles.dart'} 2024-11-20 08:06:40,484 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-20 08:06:40,484 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-20 08:06:40,484 DEBUG (core.event_handling.base_subscriber) [Misc-03] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 08:06:40,484 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-20 08:06:40,485 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/1732090000.13089.pdkghe6p/yatmppnbj022u --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 08:06:40,485 DEBUG (build.genconf) [Misc-02] Copy conf /home/runner/.ya/build/conf/dist-release-9efc4ef9baf1fc778c40c5e443395e41/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf 2024-11-20 08:06:40,488 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-20 08:06:40,488 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.488942 2024-11-20 08:06:40,489 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished=1732090000.488942 2024-11-20 08:06:40,489 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished - 1732090000.488942 2024-11-20 08:06:40,489 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.4892569 2024-11-20 08:06:40,489 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_started=1732090000.4892569 2024-11-20 08:06:40,489 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_started - 1732090000.4892569 2024-11-20 08:06:40,489 DEBUG (build.ymake2) [Misc-02] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-release-asan-pic', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f1406f6c400>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/makefiles.dart'} 2024-11-20 08:06:40,489 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-20 08:06:40,489 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-20 08:06:40,490 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-20 08:06:40,490 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/1732090000.13089.pdkghe6p/yatmpcrh98_ky --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 08:06:40,494 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732090000494124,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":0} 2024-11-20 08:06:40,494 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000494271,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,495 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732090000494315,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,495 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000494326,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,495 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732090000494331,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,495 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732090000494642,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,495 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732090000494648,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,498 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732090000498001,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":1} 2024-11-20 08:06:40,498 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000498120,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,498 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732090000498159,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,498 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000498170,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,498 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732090000498174,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,499 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732090000498471,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,499 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732090000498477,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,674 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732090000674430,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,675 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732090000674457,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,676 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732090000676095,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,676 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732090000676110,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,677 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732090000677330,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,677 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732090000677474,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,677 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732090000677355,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,678 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732090000677483,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,679 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732090000679322,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,679 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732090000679339,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,680 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732090000680743,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,680 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732090000680752,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,811 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732090000811519,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,814 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732090000813971,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,846 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846333,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846363,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846368,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846510,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732090000846515,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846594,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,847 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846599,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846603,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846607,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846611,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846615,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846619,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000846623,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732090000847657,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-20 08:06:40,848 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000847667,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732090000847675,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000848875,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732090000847679,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000848899,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732090000847685,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000848904,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,849 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000847690,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849002,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732090000847695,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732090000849008,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732090000847699,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849058,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732090000847703,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849064,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849068,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,850 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849072,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849076,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849080,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849084,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000849087,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732090000850080,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000850090,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732090000850098,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,851 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732090000850103,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,852 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732090000850107,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,852 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000850113,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,852 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732090000850118,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-20 08:06:40,852 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732090000850122,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-20 08:06:40,852 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732090000850126,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,949 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.1011234522,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732090000948850,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,949 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.09871703969,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732090000948855,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,949 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732090000948883,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732090000948883,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000948894,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000948894,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000948902,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000948902,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732090000948959,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,950 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732090000948959,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,950 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":13192,"Sub":"","Type":"Debug","_timestamp":1732090000949056,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,950 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":13194,"Sub":"","Type":"Debug","_timestamp":1732090000949056,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732090000949060,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732090000949060,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9021773; load time = 10636; loaded MD5 time = 834; Max loaded MD5 time = 6; loaded count = 2174; max load time = 54; mapped size = 40632376; mapped MD5 time = 6804; Max mapped MD5 time = 420; mapped count = 130; map time = 792; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3557; lstat min us = 0; lstat avr us = 1; lstat max us = 22; 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 = 2386; readdir min us = 0; readdir avr us = 0; readdir max us = 45; (opendir + readdir) sum us = 2386; (lstat + opendir + readdir) sum us = 5943;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732090000949099,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9021773; load time = 9857; loaded MD5 time = 819; Max loaded MD5 time = 17; loaded count = 2174; max load time = 46; mapped size = 40632376; mapped MD5 time = 6321; Max mapped MD5 time = 406; mapped count = 130; map time = 754; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3212; lstat min us = 1; lstat avr us = 1; lstat max us = 26; 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 = 2137; readdir min us = 0; readdir avr us = 0; readdir max us = 38; (opendir + readdir) sum us = 2137; (lstat + opendir + readdir) sum us = 5349;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732090000949099,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2386,"LoadTime":10636,"LoadedCount":2174,"LoadedMD5Time":834,"LoadedSize":9021773,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5943,"LstatMaxUs":22,"LstatMinUs":0,"LstatSumUs":3557,"MapTime":792,"MappedCount":130,"MappedMD5Time":6804,"MappedSize":40632376,"MaxLoadTime":54,"MaxLoadedMD5Time":6,"MaxMappedMD5Time":420,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":45,"ReaddirMinUs":0,"ReaddirSumUs":2386,"_timestamp":1732090000949114,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-20 08:06:40,951 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2137,"LoadTime":9857,"LoadedCount":2174,"LoadedMD5Time":819,"LoadedSize":9021773,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5349,"LstatMaxUs":26,"LstatMinUs":1,"LstatSumUs":3212,"MapTime":754,"MappedCount":130,"MappedMD5Time":6321,"MappedSize":40632376,"MaxLoadTime":46,"MaxLoadedMD5Time":17,"MaxMappedMD5Time":406,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":38,"ReaddirMinUs":0,"ReaddirSumUs":2137,"_timestamp":1732090000949114,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9021773,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732090000949128,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9021773,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732090000949127,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":10636,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732090000949132,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9857,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732090000949132,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732090000949136,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732090000949135,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732090000949139,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732090000949138,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,952 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":792,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732090000949141,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":754,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732090000949141,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732090000949145,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732090000949144,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732090000949147,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732090000949147,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9021773; load time = 10636; loaded count = 2174; max load time = 54;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732090000949156,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9021773; load time = 9857; loaded count = 2174; max load time = 46;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732090000949156,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":10636,"LoadedCount":2174,"LoadedSize":9021773,"MaxLoadTime":54,"_timestamp":1732090000949161,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-20 08:06:40,953 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":9857,"LoadedCount":2174,"LoadedSize":9021773,"MaxLoadTime":46,"_timestamp":1732090000949161,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-20 08:06:40,954 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"parse time = 4; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732090000949173,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,954 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 4; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732090000949173,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,954 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":4,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732090000949178,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-20 08:06:40,954 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":4,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732090000949178,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-20 08:06:40,954 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":1732090000949193,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,954 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":1732090000949193,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,954 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732090000949198,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732090000949198,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-20 08:06:40,955 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":1732090000949214,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,955 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":1732090000949216,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732090000949219,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732090000949221,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732090000949224,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732090000949227,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,955 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732090000949228,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-20 08:06:40,956 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732090000949230,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-20 08:06:40,956 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":1732090000949235,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,956 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":1732090000949237,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,956 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732090000949241,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-20 08:06:40,956 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732090000949242,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-20 08:06:40,956 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732090000949245,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,956 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732090000949246,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,956 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":1732090000949407,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,956 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":1732090000949394,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732090000949413,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732090000949399,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732090000949417,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732090000949403,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732090000949420,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732090000949406,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732090000949424,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,957 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732090000949410,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732090000949427,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732090000949413,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732090000949437,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732090000949423,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732090000950068,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732090000950056,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732090000950071,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732090000950060,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,958 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732090000952968,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,959 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732090000952952,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,959 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732090000952973,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,959 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732090000952958,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,959 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732090000953027,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,959 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-release-asan-nopic). Tool targets: set() 2024-11-20 08:06:40,959 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732090000953011,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,960 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-release-asan-pic). Tool targets: set() 2024-11-20 08:06:40,960 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732090000953030,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,960 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732090000.9602516 2024-11-20 08:06:40,960 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732090000953033,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,960 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732090000.9602516 2024-11-20 08:06:40,960 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732090000.9602516 2024-11-20 08:06:40,960 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-20 08:06:40,960 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732090000.9608774 2024-11-20 08:06:40,960 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732090000.9608774 2024-11-20 08:06:40,960 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732090000.9608774 2024-11-20 08:06:40,961 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732090000.9611077 2024-11-20 08:06:40,961 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732090000.9611077 2024-11-20 08:06:40,961 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732090000.9611077 2024-11-20 08:06:40,961 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732090000.9613495 2024-11-20 08:06:40,961 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732090000.9613495 2024-11-20 08:06:40,961 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732090000.9613495 2024-11-20 08:06:40,961 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732090000953035,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,961 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732090000953014,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,961 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732090000953038,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,961 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732090000953017,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732090000953040,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732090000953019,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732090000953102,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732090000953022,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732090000953104,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732090000953025,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,962 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":1732090000954171,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,962 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732090000953087,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,962 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":1732090000954185,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732090000953089,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000954193,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,963 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":1732090000954168,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000954199,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,963 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":1732090000954182,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732090000955665,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000954191,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,963 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732090000955673,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000954197,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732090000956139,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732090000955508,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732090000956175,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732090000955515,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732090000956178,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732090000955979,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732090000956673,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732090000956015,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,964 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000956825,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732090000956018,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732090000956830,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732090000956513,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732090000956832,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000956663,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000956836,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732090000956668,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,965 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732090000956876,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732090000956671,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732090000956878,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000956675,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732090000956883,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732090000956714,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732090000957058,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,966 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732090000956717,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732090000957084,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732090000956722,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732090000957842,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732090000956894,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732090000957909,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732090000956921,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732090000957915,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732090000957671,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,967 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000957920,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732090000957738,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732090000957925,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732090000957745,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732090000961342,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000957750,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732090000961349,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732090000957754,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732090000961166,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,968 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000961364,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,969 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732090000961174,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,969 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000961189,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,970 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":1732090000969585,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,970 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":1732090000969595,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-20 08:06:40,970 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":1732090000969872,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,970 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000969604,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,970 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":1732090000969881,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.008436926965,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732090000969616,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000969890,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732090000969623,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.008546741775,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732090000969901,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732090000969627,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732090000969908,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000970601,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732090000969911,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 08:06:40,971 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732090000970608,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,972 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000970865,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,972 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732090000970625,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-20 08:06:40,972 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732090000970873,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,972 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":1732090000970642,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,972 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732090000970887,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-20 08:06:40,973 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":1732090000970650,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-20 08:06:40,973 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":1732090000970902,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,973 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01298807084,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732090000970738,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,974 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":1732090000970908,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732090000970742,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01307627428,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732090000970996,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732090000970770,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732090000971001,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732090000970774,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732090000971028,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,974 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":1732090000970783,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,974 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732090000971031,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732090000970788,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-20 08:06:40,975 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":1732090000971041,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000974268,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732090000971046,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000974283,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000974283,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732090000974290,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-20 08:06:40,975 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000974298,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000974306,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732090000974303,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":13194,"Sub":"","Type":"Debug","_timestamp":1732090000974319,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000974318,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732090000975149,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":13192,"Sub":"","Type":"Debug","_timestamp":1732090000974331,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4771519904,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732090000975159,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732090000975144,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 08:06:40,976 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732090000975167,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":1} 2024-11-20 08:06:40,977 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4809748671,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732090000975155,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 08:06:40,977 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732090000975163,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":0} 2024-11-20 08:06:40,983 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/1732090000.13089.pdkghe6p/yatmppnbj022u --config /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 08:06:40,984 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732090000.484455585479736,"finish":1732090000.485055208206177,"duration":0.00059962272644},"execution":{"start":1732090000.485056161880493,"finish":1732090000.983343601226807,"duration":0.498287439346314},"postprocessing":{"start":1732090000.983346223831177,"finish":1732090000.983562707901001,"duration":0.000216484069824}},"purpose":"default-linux-x86_64-release-asan-nopic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732090000.494123935699463,"finish":1732090000.975162982940674,"duration":0.481039047241211},"Load configuration from cache":{"start":1732090000.494314908981323,"finish":1732090000.494330883026123,"duration":0.0000159740448},"Load configuration (no cache)":{"start":1732090000.494647979736328,"finish":1732090000.674429893493652,"duration":0.179781913757324},"Compile and RecalcAll conditions":{"start":1732090000.67445707321167,"finish":1732090000.676095008850098,"duration":0.001637935638428},"Fold global commands":{"start":1732090000.676110029220581,"finish":1732090000.677474021911621,"duration":0.00136399269104},"Load plugins":{"start":1732090000.677483081817627,"finish":1732090000.811518907546997,"duration":0.13403582572937},"ymake main":{"start":1732090000.846514940261841,"finish":1732090000.975143909454346,"duration":0.128628969192505},"Build graph":{"start":1732090000.847685098648071,"finish":1732090000.949244976043701,"duration":0.10155987739563},"Configure graph":{"start":1732090000.847702980041504,"finish":1732090000.948883056640625,"duration":0.101180076599121},"Find Lost Includes":{"start":1732090000.948959112167358,"finish":1732090000.949059963226318,"duration":0.00010085105896},"Set reachable nodes":{"start":1732090000.949436902999878,"finish":1732090000.95006799697876,"duration":0.000631093978882},"Sort edges":{"start":1732090000.950071096420288,"finish":1732090000.952967882156372,"duration":0.002896785736084},"Report Foreign Platform Events":{"start":1732090000.952972888946533,"finish":1732090000.953027009963989,"duration":0.000054121017456},"Check blacklist":{"start":1732090000.953030109405518,"finish":1732090000.953032970428467,"duration":0.000002861022949},"Check isolated projects":{"start":1732090000.953035116195679,"finish":1732090000.953037977218628,"duration":0.000002861022949},"Report Configure Events":{"start":1732090000.953039884567261,"finish":1732090000.953102111816406,"duration":0.000062227249146},"Save and compact":{"start":1732090000.953104019165039,"finish":1732090000.955665111541748,"duration":0.002561092376709},"Detect loops":{"start":1732090000.955672979354858,"finish":1732090000.956175088882446,"duration":0.000502109527588},"Apply Dependency Management":{"start":1732090000.956177949905396,"finish":1732090000.956831932067871,"duration":0.000653982162476},"Save Dependency management cache":{"start":1732090000.95667290687561,"finish":1732090000.956830024719238,"duration":0.000157117843628},"Fill package outputs":{"start":1732090000.956876039505005,"finish":1732090000.956877946853638,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732090000.956882953643799,"finish":1732090000.957057952880859,"duration":0.000174999237061},"Find missing peerdirs":{"start":1732090000.957083940505982,"finish":1732090000.957842111587524,"duration":0.000758171081543},"Export JSON":{"start":1732090000.957909107208252,"finish":1732090000.971028089523315,"duration":0.013118982315063},"Write JSON":{"start":1732090000.95791506767273,"finish":1732090000.971000909805298,"duration":0.013085842132568},"Visit JSON":{"start":1732090000.957925081253052,"finish":1732090000.961342096328735,"duration":0.003417015075684},"Render JSON":{"start":1732090000.961349010467529,"finish":1732090000.969907999038696,"duration":0.008558988571167},"Save Uids cache":{"start":1732090000.969911098480225,"finish":1732090000.970873117446899,"duration":0.000962018966675}},"metrics":{"File access":{"loaded size":9021773,"load time":10636,"loaded MD5 time":834,"Max loaded MD5 time":6,"loaded count":2174,"max load time":54,"mapped size":40632376,"mapped MD5 time":6804,"Max mapped MD5 time":420,"mapped count":130,"map time":792,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3557,"lstat min us":0,"lstat avr us":1,"lstat max us":22,"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":2386,"readdir min us":0,"readdir avr us":0,"readdir max us":45,"(opendir + readdir) sum us":2386,"(lstat + opendir + readdir) sum us":5943},"Parsing":{"parse time":4,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/yatmppnbj022u","--config","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-89b78db1db59d8d6855caed33424337f_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.7l_ioq9k/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.498287439346314} 2024-11-20 08:06:40,984 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732090000.9843538 2024-11-20 08:06:40,984 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732090000.9843538 2024-11-20 08:06:40,984 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732090000.9843538 2024-11-20 08:06:40,985 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732090000.9855895 2024-11-20 08:06:40,985 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732090000.9855895 2024-11-20 08:06:40,985 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732090000.9855895 2024-11-20 08:06:40,985 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.9859166 2024-11-20 08:06:40,985 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_finished=1732090000.9859166 2024-11-20 08:06:40,986 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_finished - 1732090000.9859166 2024-11-20 08:06:40,986 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.98624 2024-11-20 08:06:40,986 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_started=1732090000.98624 2024-11-20 08:06:40,986 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_started - 1732090000.98624 2024-11-20 08:06:40,990 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-20 08:06:40,991 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.0003185272216796875 2024-11-20 08:06:40,991 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.9913063 2024-11-20 08:06:40,991 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_finished=1732090000.9913063 2024-11-20 08:06:40,991 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_finished - 1732090000.9913063 2024-11-20 08:06:40,991 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732090000.991624 2024-11-20 08:06:40,991 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_finished=1732090000.991624 2024-11-20 08:06:40,991 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_finished - 1732090000.991624 2024-11-20 08:06:40,992 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/1732090000.13089.pdkghe6p/yatmpcrh98_ky --config /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 08:06:40,993 DEBUG (core.event_handling.base_subscriber) [Misc-02] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 08:06:40,993 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732090000.489758491516113,"finish":1732090000.49040675163269,"duration":0.000648260116577},"execution":{"start":1732090000.490407705307007,"finish":1732090000.993053674697876,"duration":0.502645969390869},"postprocessing":{"start":1732090000.993055582046509,"finish":1732090000.993377447128296,"duration":0.000321865081787}},"purpose":"default-linux-x86_64-release-asan-pic","caches":{"FS cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"Deps cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DepManagement cache":{"loaded":false,"saved":false,"loading_enabled":true,"saving_enabled":true},"Uids cache":{"loaded":false,"saved":true,"loading_enabled":true,"saving_enabled":true,"committed":true},"DM cache":{"loaded":false,"saved":true,"loading_enabled":false,"saving_enabled":false,"committed":true}},"stages":{"ymake run":{"start":1732090000.498001098632812,"finish":1732090000.975167036056518,"duration":0.477165937423706},"Load configuration from cache":{"start":1732090000.498158931732178,"finish":1732090000.498173952102661,"duration":0.000015020370483},"Load configuration (no cache)":{"start":1732090000.498476982116699,"finish":1732090000.677330017089844,"duration":0.178853034973145},"Compile and RecalcAll conditions":{"start":1732090000.677355051040649,"finish":1732090000.679322004318237,"duration":0.001966953277588},"Fold global commands":{"start":1732090000.679338932037354,"finish":1732090000.680742979049683,"duration":0.001404047012329},"Load plugins":{"start":1732090000.680752038955688,"finish":1732090000.813971042633057,"duration":0.133219003677368},"ymake main":{"start":1732090000.849008083343506,"finish":1732090000.975148916244507,"duration":0.126140832901001},"Build graph":{"start":1732090000.850106954574585,"finish":1732090000.949245929718018,"duration":0.099138975143433},"Configure graph":{"start":1732090000.850126028060913,"finish":1732090000.948883056640625,"duration":0.098757028579712},"Find Lost Includes":{"start":1732090000.948959112167358,"finish":1732090000.949059963226318,"duration":0.00010085105896},"Set reachable nodes":{"start":1732090000.94942307472229,"finish":1732090000.950056076049805,"duration":0.000633001327515},"Sort edges":{"start":1732090000.95005989074707,"finish":1732090000.952951908111572,"duration":0.002892017364502},"Report Foreign Platform Events":{"start":1732090000.952958106994629,"finish":1732090000.95301103591919,"duration":0.000052928924561},"Check blacklist":{"start":1732090000.953013896942139,"finish":1732090000.953016996383667,"duration":0.000003099441528},"Check isolated projects":{"start":1732090000.9530189037323,"finish":1732090000.953022003173828,"duration":0.000003099441528},"Report Configure Events":{"start":1732090000.953025102615356,"finish":1732090000.953087091445923,"duration":0.000061988830566},"Save and compact":{"start":1732090000.953088998794556,"finish":1732090000.95550799369812,"duration":0.002418994903564},"Detect loops":{"start":1732090000.955514907836914,"finish":1732090000.956015110015869,"duration":0.000500202178955},"Apply Dependency Management":{"start":1732090000.956017971038818,"finish":1732090000.956670999526978,"duration":0.000653028488159},"Save Dependency management cache":{"start":1732090000.956512928009033,"finish":1732090000.956667900085449,"duration":0.000154972076416},"Fill package outputs":{"start":1732090000.956713914871216,"finish":1732090000.956717014312744,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732090000.956722021102905,"finish":1732090000.956893920898438,"duration":0.000171899795532},"Find missing peerdirs":{"start":1732090000.956921100616455,"finish":1732090000.95767092704773,"duration":0.000749826431274},"Export JSON":{"start":1732090000.957737922668457,"finish":1732090000.970769882202148,"duration":0.013031959533691},"Write JSON":{"start":1732090000.95774507522583,"finish":1732090000.970741987228394,"duration":0.012996912002563},"Visit JSON":{"start":1732090000.957753896713257,"finish":1732090000.961165904998779,"duration":0.003412008285522},"Render JSON":{"start":1732090000.961174011230469,"finish":1732090000.96962308883667,"duration":0.008449077606201},"Save Uids cache":{"start":1732090000.969626903533936,"finish":1732090000.970607995986938,"duration":0.000981092453003}},"metrics":{"File access":{"loaded size":9021773,"load time":9857,"loaded MD5 time":819,"Max loaded MD5 time":17,"loaded count":2174,"max load time":46,"mapped size":40632376,"mapped MD5 time":6321,"Max mapped MD5 time":406,"mapped count":130,"map time":754,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3212,"lstat min us":1,"lstat avr us":1,"lstat max us":26,"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":2137,"readdir min us":0,"readdir avr us":0,"readdir max us":38,"(opendir + readdir) sum us":2137,"(lstat + opendir + readdir) sum us":5349},"Parsing":{"parse time":4,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/yatmpcrh98_ky","--config","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-9ee9d4a643e508fb4aafc1035ff3a1f6_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.3iyyr084/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.502645969390869} 2024-11-20 08:06:40,994 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732090000.9940197 2024-11-20 08:06:40,994 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732090000.9940197 2024-11-20 08:06:40,994 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732090000.9940197 2024-11-20 08:06:40,994 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732090000.9949267 2024-11-20 08:06:40,995 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732090000.9949267 2024-11-20 08:06:40,995 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732090000.9949267 2024-11-20 08:06:40,995 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.9952676 2024-11-20 08:06:40,995 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_finished=1732090000.9952676 2024-11-20 08:06:40,995 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_finished - 1732090000.9952676 2024-11-20 08:06:40,995 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.9955723 2024-11-20 08:06:40,995 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_started=1732090000.9955723 2024-11-20 08:06:40,995 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_started - 1732090000.9955723 2024-11-20 08:06:40,995 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-20 08:06:40,995 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.00011372566223144531 2024-11-20 08:06:40,995 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.9959483 2024-11-20 08:06:40,996 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_finished=1732090000.9959483 2024-11-20 08:06:40,996 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_finished - 1732090000.9959483 2024-11-20 08:06:40,996 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732090000.9961805 2024-11-20 08:06:40,996 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_finished=1732090000.9961805 2024-11-20 08:06:40,996 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_finished - 1732090000.9961805 2024-11-20 08:06:40,996 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-release-asan: 0.03527712821960449 2024-11-20 08:06:40,996 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732090000.9967728 2024-11-20 08:06:40,996 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732090000.9967728 2024-11-20 08:06:40,996 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732090000.9967728 2024-11-20 08:06:40,997 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732090000.9970808 2024-11-20 08:06:40,997 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732090000.9970808 2024-11-20 08:06:40,997 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732090000.9970808 2024-11-20 08:06:40,997 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-release-asan: 0.0006067752838134766 2024-11-20 08:06:40,997 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732090000.9972737 2024-11-20 08:06:40,997 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732090000.9972737 2024-11-20 08:06:40,997 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732090000.9972737 2024-11-20 08:06:40,997 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732090000.9974227 2024-11-20 08:06:40,997 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732090000.9974227 2024-11-20 08:06:40,997 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732090000.9974227 2024-11-20 08:06:40,997 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-release-asan: 0.0003733634948730469 2024-11-20 08:06:40,997 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-20 08:06:40,997 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732090000.9976962 2024-11-20 08:06:40,997 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732090000.9976962 2024-11-20 08:06:40,997 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732090000.9976962 2024-11-20 08:06:40,997 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732090000.9978611 2024-11-20 08:06:40,997 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732090000.9978611 2024-11-20 08:06:40,997 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732090000.9978611 2024-11-20 08:06:40,998 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-release-asan: 0.0003991127014160156 2024-11-20 08:06:40,998 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-20 08:06:40,998 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732090000.9980874 2024-11-20 08:06:40,998 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732090000.9980874 2024-11-20 08:06:40,998 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732090000.9980874 2024-11-20 08:06:40,998 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-20 08:06:40,998 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732090000.9989522 2024-11-20 08:06:40,999 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732090000.9989522 2024-11-20 08:06:40,999 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732090000.9989522 2024-11-20 08:06:40,999 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732090000.999501 2024-11-20 08:06:40,999 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732090000.999501 2024-11-20 08:06:40,999 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732090000.999501 2024-11-20 08:06:40,999 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-20 08:06:41,016 DEBUG (build.genconf) [Misc-04] Generating conf into /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf with cmd ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', '/home/runner/actions_runner/_work/ydb/ydb/build/ymake_conf.py', '/home/runner/actions_runner/_work/ydb/ydb', 'dist-release', 'no', '--toolchain-params', 'eyJib3R0bGVfbmFtZSI6ImNsYW5nMTgiLCJlbnYiOnsiQ1BBVEgiOlsiIl0sIkxJQlJBUllfUEFUSCI6WyIiXSwiU0RLUk9PVCI6WyIiXX0sImV4ZWN1dGFibGVfcGF0aCI6ImMrKyIsImZsYWdzIjp7IlNBTklUSVpFUl9UWVBFIjoiYWRkcmVzcyJ9LCJmb3JtdWxhIjp7ImJ5X3BsYXRmb3JtIjp7ImRhcndpbi1hcm02NCI6eyJ1cmkiOiJzYnI6NzQ1MTYwMjYwOSJ9LCJkYXJ3aW4teDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNTg1OTYyIn0sImxpbnV4LWFhcmNoNjQiOnsidXJpIjoic2JyOjc0NTE3MTIzMzIifSwibGludXgteDg2XzY0Ijp7InVyaSI6InNicjo3NDUxNDY2NDA3In0sIndpbjMyLXg4Nl82NCI6eyJ1cmkiOiJzYnI6NzQ1MTQwNjkwMCJ9fX0sIm5hbWUiOiJjbGFuZzE4IiwicGFyYW1zIjp7ImNfY29tcGlsZXIiOiIkKENMQU5HLTE3MzUwNTY4MjEpL2Jpbi9jbGFuZyIsImN4eF9jb21waWxlciI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2NsYW5nKysiLCJnY2NfdmVyc2lvbiI6IjE4IiwibGx2bS1zeW1ib2xpemVyIjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1zeW1ib2xpemVyIiwibWF0Y2hfcm9vdCI6IkNMQU5HLTE3MzUwNTY4MjEiLCJvYmpjb3B5IjoiJChDTEFORy0xNzM1MDU2ODIxKS9iaW4vbGx2bS1vYmpjb3B5IiwicHJvZmlsZXMiOiIkKFhDT0RFX1RPT0xTX1JPT1Qtc2JyOjc5OTAxNzc3MSkvWGNvZGUvQ29udGVudHMvRGV2ZWxvcGVyL1BsYXRmb3Jtcy9pUGhvbmVPUy5wbGF0Zm9ybS9EZXZlbG9wZXIvTGlicmFyeS9Db3JlU2ltdWxhdG9yL1Byb2ZpbGVzIiwic2ltY3RsIjoiJChYQ09ERV9UT09MU19ST09ULXNicjo3OTkwMTc3NzEpL1hjb2RlL1N5c3RlbVJvb3QvUHJpdmF0ZUZyYW1ld29ya3MvQ29yZVNpbXVsYXRvci5mcmFtZXdvcmsvUmVzb3VyY2VzL2Jpbi9zaW1jdGwiLCJzdHJpcCI6IiQoQ0xBTkctMTczNTA1NjgyMSkvYmluL2xsdm0tc3RyaXAiLCJ0eXBlIjoiY2xhbmciLCJ1c2VfYnVuZGxlIjp0cnVlLCJ3ZXJyb3JfbW9kZSI6ImFsbCJ9LCJwbGF0Zm9ybSI6eyJob3N0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifSwidGFyZ2V0Ijp7ImFyY2giOiJ4ODZfNjQiLCJvcyI6IkxJTlVYIiwidG9vbGNoYWluIjoiZGVmYXVsdCIsInZpc2libGVfbmFtZSI6ImNsYW5nMTgifX0sInBsYXRmb3JtX25hbWUiOiJERUZBVUxULUxJTlVYLVg4Nl82NCIsInRvb2xfdmFyIjoiQ0xBTkctMTczNTA1NjgyMSJ9', '-l', '-D', 'APPLE_SDK_LOCAL=yes', '-D', 'CFLAGS=-fno-omit-frame-pointer', '-D', 'CLANG18=yes', '-D', 'FORCE_NO_PIC=yes', '-D', 'IS_CROSS_SANITIZE=yes', '-D', 'IS_CROSS_TOOLS=yes', '-D', 'OPENSOURCE=yes', '-D', 'SANITIZER_TYPE=address', '-D', 'TESTS_REQUESTED=yes', '-D', 'TRAVERSE_DEPENDS=yes', '-D', 'TRAVERSE_RECURSE_FOR_TESTS=yes', '-D', 'USE_AIO=static', '-D', 'USE_CLANG_CL=yes', '-D', 'USE_ICONV=static', '-D', 'USE_IDN=static', '-D', 'USE_PREBUILT_TOOLS=no', '-D', 'YA=yes'] and env {'PATH': '/home/runner/actions_runner/_work/ydb/ydb/ydb/ci/scripts:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'Y_PYTHON_ENTRY_POINT': ':main'} 2024-11-20 08:06:41,157 DEBUG (build.genconf) [Misc-04] Copy conf /home/runner/.ya/build/conf/dist-release-06d29a8eb873efd32d30df7100bcb0f9/ymake.conf to /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf 2024-11-20 08:06:41,157 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-20 08:06:41,157 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732090001.15765 2024-11-20 08:06:41,157 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732090001.15765 2024-11-20 08:06:41,157 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732090001.15765 2024-11-20 08:06:41,157 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732090001.1578586 2024-11-20 08:06:41,157 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732090001.1578586 2024-11-20 08:06:41,157 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732090001.1578586 2024-11-20 08:06:41,158 DEBUG (build.ymake2) [Misc-04] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'test_tool_tc1-nopic-global', 'cpp': True, 'custom_build_directory': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19', 'build_type': 'release', 'abs_targets': ['/home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2', '/home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3', '/home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib'], 'debug_options': [], 'custom_conf': '/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf', 'continue_on_fail': False, 'ymake_bin': None, 'warn_mode': ['dirloops', 'ChkPeers'], 'build_depends': 3, 'arcadia_tests_data_path': '/home/runner/actions_runner/_work/ydb/arcadia_tests_data', 'checkout_data_by_ya': False, 'strict_inputs': False, 'dump_inputs_map': False, 'ev_listener': .event_listener_debug_id_wrapper at 0x7f1408bcd8a0>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/makefiles.dart'} 2024-11-20 08:06:41,158 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-20 08:06:41,158 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-20 08:06:41,158 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 08:06:41,158 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-20 08:06:41,158 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/1732090000.13089.pdkghe6p/yatmpjdyfcz_u --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' with env: { } 2024-11-20 08:06:41,575 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/1732090000.13089.pdkghe6p/yatmpjdyfcz_u --config /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf --plugins-root /home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins --build-root /home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19 --test-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/makefiles.dart --dump-build-plan - --quiet --events PSLGE /home/runner/actions_runner/_work/ydb/ydb/build/platform/test_tool /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/go_tools /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py2 /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/flake8_py3 /home/runner/actions_runner/_work/ydb/ydb/build/platform/java/jstyle_lib' finished 2024-11-20 08:06:41,575 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 08:06:41,576 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732090001.158268451690674,"finish":1732090001.158908367156982,"duration":0.000639915466309},"execution":{"start":1732090001.15890908241272,"finish":1732090001.575741291046143,"duration":0.416832208633423},"postprocessing":{"start":1732090001.575743913650513,"finish":1732090001.576179027557373,"duration":0.00043511390686}},"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/1732090000.13089.pdkghe6p/yatmpjdyfcz_u","--config","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a/ymake.conf","--plugins-root","/home/runner/actions_runner/_work/ydb/ydb/build/plugins,/home/runner/actions_runner/_work/ydb/ydb/build/internal/plugins","--build-root","/home/runner/.ya/build/conf/dist-release-x-50c47e5d1a77fdc41ebe2ced4dea973a_d357a1f7839b099012272bcb9853ec19","--test-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/gen_graph.oluj19ov/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.416832208633423} 2024-11-20 08:06:41,576 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732090001.5766597 2024-11-20 08:06:41,576 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732090001.5766597 2024-11-20 08:06:41,576 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732090001.5766597 2024-11-20 08:06:41,576 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732090001.5769553 2024-11-20 08:06:41,577 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732090001.5769553 2024-11-20 08:06:41,577 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732090001.5769553 2024-11-20 08:06:41,577 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732090001.5771534 2024-11-20 08:06:41,577 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732090001.5771534 2024-11-20 08:06:41,577 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732090001.5771534 2024-11-20 08:06:41,577 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732090001.577441 2024-11-20 08:06:41,577 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732090001.577441 2024-11-20 08:06:41,577 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732090001.577441 2024-11-20 08:06:41,578 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732090001.578099 2024-11-20 08:06:41,578 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732090001.578099 2024-11-20 08:06:41,578 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732090001.578099 2024-11-20 08:06:41,580 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732090001.5802717 2024-11-20 08:06:41,580 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732090001.5802717 2024-11-20 08:06:41,580 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732090001.5802717 2024-11-20 08:06:41,580 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732090001.5805774 2024-11-20 08:06:41,580 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732090001.5805774 2024-11-20 08:06:41,580 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732090001.5805774 2024-11-20 08:06:41,580 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732090001.5807605 2024-11-20 08:06:41,580 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732090001.5807605 2024-11-20 08:06:41,580 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732090001.5807605 2024-11-20 08:06:41,581 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732090001.5819397 2024-11-20 08:06:41,582 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732090001.5819397 2024-11-20 08:06:41,582 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732090001.5819397 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f1406725f80>' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-20 08:06:41,582 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-20 08:06:41,583 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-20 08:06:41,583 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-20 08:06:41,583 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-20 08:06:41,583 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-20 08:06:41,583 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-release-asan: 0.0012688636779785156 2024-11-20 08:06:41,583 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-release-asan 2024-11-20 08:06:41,583 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732090001.583511 2024-11-20 08:06:41,583 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732090001.583511 2024-11-20 08:06:41,583 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732090001.583511 2024-11-20 08:06:41,583 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732090001.5836859 2024-11-20 08:06:41,583 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732090001.5836859 2024-11-20 08:06:41,583 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732090001.5836859 2024-11-20 08:06:41,583 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732090001.5838237 2024-11-20 08:06:41,583 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732090001.5838237 2024-11-20 08:06:41,583 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732090001.5838237 2024-11-20 08:06:41,584 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-20 08:06:41,584 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 08:06:41,584 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-20 08:06:41,584 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-20 08:06:41,584 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732090001.5848937 2024-11-20 08:06:41,584 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732090001.5848937 2024-11-20 08:06:41,585 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732090001.5848937 2024-11-20 08:06:41,585 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732090001.5850751 2024-11-20 08:06:41,585 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732090001.5850751 2024-11-20 08:06:41,585 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732090001.5850751 2024-11-20 08:06:41,585 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732090001.5852365 2024-11-20 08:06:41,585 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732090001.5852365 2024-11-20 08:06:41,585 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732090001.5852365 2024-11-20 08:06:41,585 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.2943217754364014 2024-11-20 08:06:41,585 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732090001.5854344 2024-11-20 08:06:41,585 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732090001.5854344 2024-11-20 08:06:41,585 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732090001.5854344 2024-11-20 08:06:41,585 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 08:06:41,585 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.00025963783264160156 2024-11-20 08:06:41,585 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732090001.585683 2024-11-20 08:06:41,585 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732090001.585683 2024-11-20 08:06:41,585 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732090001.585683 2024-11-20 08:06:41,585 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.0002148151397705078 2024-11-20 08:06:41,585 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 08:06:41,586 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 08:06:41,586 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732090001.5861747 2024-11-20 08:06:41,586 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732090001.5861747 2024-11-20 08:06:41,586 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732090001.5861747 2024-11-20 08:06:41,586 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-20 08:06:41,586 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 2.288818359375e-05 2024-11-20 08:06:41,586 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732090001.5864456 2024-11-20 08:06:41,586 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732090001.5864456 2024-11-20 08:06:41,586 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732090001.5864456 2024-11-20 08:06:41,586 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0007441043853759766 2024-11-20 08:06:41,586 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732090001.5866485 2024-11-20 08:06:41,586 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732090001.5866485 2024-11-20 08:06:41,586 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732090001.5866485 2024-11-20 08:06:41,586 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732090001.5867755 2024-11-20 08:06:41,586 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732090001.5867755 2024-11-20 08:06:41,586 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732090001.5867755 2024-11-20 08:06:41,586 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732090001.5869153 2024-11-20 08:06:41,586 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732090001.5869153 2024-11-20 08:06:41,587 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732090001.5869153 2024-11-20 08:06:41,587 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:06:41,587 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:06:41,587 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 08:06:41,587 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732090001.5872486 2024-11-20 08:06:41,587 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732090001.5872486 2024-11-20 08:06:41,587 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732090001.5872486 2024-11-20 08:06:41,587 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732090001.5874026 2024-11-20 08:06:41,587 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732090001.5874026 2024-11-20 08:06:41,587 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732090001.5874026 2024-11-20 08:06:41,587 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732090001.5877252 2024-11-20 08:06:41,587 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732090001.5877252 2024-11-20 08:06:41,587 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732090001.5877252 2024-11-20 08:06:41,587 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732090001.5878868 2024-11-20 08:06:41,587 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732090001.5878868 2024-11-20 08:06:41,587 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732090001.5878868 2024-11-20 08:06:41,588 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0014481544494628906 2024-11-20 08:06:41,588 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732090001.5880942 2024-11-20 08:06:41,588 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732090001.5880942 2024-11-20 08:06:41,588 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732090001.5880942 2024-11-20 08:06:41,588 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732090001.5883403 2024-11-20 08:06:41,588 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732090001.5883403 2024-11-20 08:06:41,588 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732090001.5883403 2024-11-20 08:06:41,588 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732090001.5886626 2024-11-20 08:06:41,588 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732090001.5886626 2024-11-20 08:06:41,588 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732090001.5886626 2024-11-20 08:06:41,588 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732090001.588823 2024-11-20 08:06:41,588 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732090001.588823 2024-11-20 08:06:41,588 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732090001.588823 2024-11-20 08:06:41,589 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732090001.5890486 2024-11-20 08:06:41,589 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732090001.5890486 2024-11-20 08:06:41,589 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732090001.5890486 2024-11-20 08:06:41,589 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732090001.5892026 2024-11-20 08:06:41,589 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732090001.5892026 2024-11-20 08:06:41,589 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732090001.5892026 2024-11-20 08:06:41,589 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.3346848487854004 2024-11-20 08:06:41,591 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732090001.5911925 2024-11-20 08:06:41,591 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732090001.5911925 2024-11-20 08:06:41,591 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732090001.5911925 2024-11-20 08:06:41,591 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-20 08:06:41,591 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-20 08:06:41,591 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-20 08:06:41,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732090001.591545 2024-11-20 08:06:41,591 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732090001.591545 2024-11-20 08:06:41,591 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732090001.591545 2024-11-20 08:06:41,591 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732090001.5917084 2024-11-20 08:06:41,591 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732090001.5917084 2024-11-20 08:06:41,591 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732090001.5917084 2024-11-20 08:06:41,591 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-20 08:06:41,591 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732090001.5919514 2024-11-20 08:06:41,592 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732090001.5919514 2024-11-20 08:06:41,592 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732090001.5919514 2024-11-20 08:06:41,592 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-20 08:06:41,594 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-20 08:06:41,595 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-20 08:06:41,596 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-20 08:06:41,598 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-20 08:06:41,599 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-20 08:06:41,600 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-20 08:06:41,601 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-20 08:06:41,603 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-20 08:06:41,604 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-20 08:06:41,605 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-20 08:06:41,607 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-20 08:06:41,608 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-20 08:06:41,609 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-20 08:06:41,617 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/3067455a51595241317750497931634732724c63734100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,618 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/3769554a53624e61733865673555613741466e345a4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,618 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,618 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing 0gEZQYRA1wPIy1cG2rLcsA => True 2024-11-20 08:06:41,619 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/45496c4c484f70786e4675725a6374484c7a2d47506700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,619 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing 7iUJSbNas8eg5Ua7AFn4ZA => True 2024-11-20 08:06:41,619 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,620 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/777467344d4b4b4950565342647635592d78536d646700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,620 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 08:06:41,620 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing EIlLHOpxnFurZctHLz-GPg => True 2024-11-20 08:06:41,621 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 08:06:41,621 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing wtg4MKKIPVSBdv5Y-xSmdg => True 2024-11-20 08:06:41,622 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/52696d5031345a792d344c41376f5634665250796c7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,622 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing RimP14Zy-4LA7oV4fRPylw => True 2024-11-20 08:06:41,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 08:06:41,623 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/3675566751716f6d4c3279683532743357692d354f6700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,624 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,624 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing 6uVgQqomL2yh52t3Wi-5Og => True 2024-11-20 08:06:41,624 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing iTMgoEEhV9D7LHyD5DWuLw => True 2024-11-20 08:06:41,626 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:41,626 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => False 2024-11-20 08:06:41,627 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/7554324d64504d31796451424f6d726e72646437315100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,627 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7a652d367053414577366354443768636b456b46427700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,627 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing uT2MdPM1ydQBOmrnrdd71Q => True 2024-11-20 08:06:41,628 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing ze-6pSAEw6cTD7hckEkFBw => True 2024-11-20 08:06:41,628 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-20 08:06:41,628 DEBUG (build.graph) [MainThread] stripped 10, left 3 nodes 2024-11-20 08:06:41,633 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732090001.6331549 2024-11-20 08:06:41,633 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732090001.6331549 2024-11-20 08:06:41,633 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732090001.6331549 2024-11-20 08:06:41,633 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.04387855529785156 2024-11-20 08:06:41,633 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732090001.63366 2024-11-20 08:06:41,633 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732090001.63366 2024-11-20 08:06:41,633 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732090001.63366 2024-11-20 08:06:41,633 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732090001.6338642 2024-11-20 08:06:41,633 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732090001.6338642 2024-11-20 08:06:41,633 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732090001.6338642 2024-11-20 08:06:41,634 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 08:06:41,634 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:06:41,634 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:06:41,634 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:06:41,634 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:06:41,634 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:06:41,634 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:06:41,635 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 08:06:41,635 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 08:06:41,635 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 08:06:41,635 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:06:41,635 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:06:41,635 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:06:41,635 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:06:41,635 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:06:41,635 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:06:41,635 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 08:06:41,636 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-20 08:06:41,636 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-20 08:06:41,636 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732090001.6361072 2024-11-20 08:06:41,636 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732090001.6361072 2024-11-20 08:06:41,636 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732090001.6361072 2024-11-20 08:06:41,636 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-20 08:06:41,649 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732090000.13089.pdkghe6p/1308957l1_6hh', '0'] 2024-11-20 08:06:41,649 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-20 08:06:41,675 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/myn3/STAMP 2024-11-20 08:06:41,675 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 5.316734313964844e-05 2024-11-20 08:06:41,675 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00015616416931152344 2024-11-20 08:06:41,675 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 6.246566772460938e-05 2024-11-20 08:06:41,675 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 5.841255187988281e-05 2024-11-20 08:06:41,676 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-20 08:06:41,834 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-20 08:06:41,839 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid kaKhVKkwv1kkbgok_0Mn2A) address: (12671, 110582, 'unix:/home/runner/.ya/build/cache/7/psingpqcYrl.tmp') 2024-11-20 08:06:41,842 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for kaKhVKkwv1kkbgok_0Mn2A created (provider=None) 2024-11-20 08:06:41,843 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-20 08:06:41,843 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-20 08:06:41,851 DEBUG (urllib3.connectionpool) [Worker-005] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 404 0 2024-11-20 08:06:41,851 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-005] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => False 2024-11-20 08:06:41,852 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-012] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-20 08:06:41,853 DEBUG (yalibrary.toolscache) [Worker-012] Tools cache attempt: (request for resource 7458630270) 2024-11-20 08:06:41,854 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-005] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-20 08:06:41,854 DEBUG (yalibrary.toolscache) [Worker-005] Tools cache attempt: (request for resource 7336031045) 2024-11-20 08:06:41,854 DEBUG (yalibrary.toolscache) [Worker-012] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:06:41,856 DEBUG (yalibrary.fetcher.cache_helper) [Worker-012] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 08:06:41,856 DEBUG (library.python.filelock) [Worker-012] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 08:06:41,856 DEBUG (yalibrary.fetcher.cache_helper) [Worker-012] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 08:06:41,856 DEBUG (yalibrary.toolscache) [Worker-005] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 08:06:41,856 DEBUG (exts.http_client) [Worker-012] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.3ijosb7d.qq463ml8.part, expect md5- 2024-11-20 08:06:41,857 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 08:06:41,857 DEBUG (library.python.filelock) [Worker-005] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 08:06:41,858 DEBUG (yalibrary.fetcher.cache_helper) [Worker-005] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 08:06:41,858 DEBUG (exts.http_client) [Worker-005] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.vbxvt55c.jbcy9upg.part, expect md5- 2024-11-20 08:06:41,871 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:06:41,871 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing iTMgoEEhV9D7LHyD5DWuLw => True 2024-11-20 08:06:41,881 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /ac/69544d676f454568563944374c487944354457754c7700000000000000000001 HTTP/1.1" 200 513 2024-11-20 08:06:41,890 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/04a8963e17cb10a6dd4ffee67f52ed75a072f95a9a80c059060e112d2d19173d HTTP/1.1" 200 45 2024-11-20 08:06:41,900 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/7cec2c62ccbad1fb7f3e3317002e11a4f7c7763895544c064603dc763e812a86 HTTP/1.1" 200 245 2024-11-20 08:06:41,910 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "GET /cas/e7fbc2bccde2f0e9002e41eb33d99f3354c8492c5599f509028ba20c9d4a5b70 HTTP/1.1" 200 None 2024-11-20 08:06:41,927 DEBUG (exts.http_client) [Worker-005] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Wed, 20 Nov 2024 08:06:41 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: 29c9d4ba5aca8848 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 08:06:41,949 DEBUG (exts.http_client) [Worker-012] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Wed, 20 Nov 2024 08:06:41 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: 56b1f35d985e56a3 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=SAS"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 08:06:42,000 DEBUG (exts.http_client) [Worker-005] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.vbxvt55c, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.142442 2024-11-20 08:06:42,001 DEBUG (yalibrary.fetcher.common) [Worker-005] extract /home/runner/.ya/tools/v4/7336031045/resource.vbxvt55c to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-20 08:06:42,173 DEBUG (library.python.filelock) [Worker-005] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 08:06:42,328 DEBUG (yalibrary.toolscache) [Worker-011] AC cache attempt: (put uid iTMgoEEhV9D7LHyD5DWuLw) 2024-11-20 08:06:49,110 DEBUG (exts.http_client) [Worker-012] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.3ijosb7d, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=7.254027 2024-11-20 08:06:49,111 DEBUG (yalibrary.fetcher.common) [Worker-012] extract /home/runner/.ya/tools/v4/7458630270/resource.3ijosb7d to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-20 08:06:49,511 DEBUG (library.python.filelock) [Worker-012] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 08:06:49,512 DEBUG (yalibrary.toolscache) [Worker-012] AC cache attempt: (get uid 2d7962fb2639527e6297eba2caf0e562) 2024-11-20 08:06:49,523 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/a38b66c50e209cbbfa54a69756cfffa9f1c9f2540432da07020a0a54a2a18711 HTTP/1.1" 404 0 2024-11-20 08:06:49,524 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing 2d7962fb2639527e6297eba2caf0e562 => False 2024-11-20 08:06:49,525 DEBUG (root) [Worker-012] Run node kaKhVKkwv1kkbgok_0Mn2A in build root /home/runner/.ya/build/build_root/myn3/000004 2024-11-20 08:07:51,153 DEBUG (yalibrary.toolscache) [Worker-028] AC cache attempt: (put uid kaKhVKkwv1kkbgok_0Mn2A) 2024-11-20 08:07:51,154 DEBUG (yalibrary.runner.tasks.result) [Worker-012] Result node kaKhVKkwv1kkbgok_0Mn2A processed successfully: True 2024-11-20 08:07:51,155 DEBUG (yalibrary.toolscache) [Worker-028] AC cache attempt: (put uid 2d7962fb2639527e6297eba2caf0e562) 2024-11-20 08:07:51,161 DEBUG (urllib3.connectionpool) [Worker-027] http://cachesrv.internal:8081 "HEAD /ac/6b614b68564b6b7776316b6b62676f6b5f304d6e324100000000000000000001 HTTP/1.1" 200 0 2024-11-20 08:07:51,162 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-027] Bazel-remote Probing kaKhVKkwv1kkbgok_0Mn2A => True 2024-11-20 08:07:51,236 DEBUG (yalibrary.runner.build_root) [Worker-037] Build root /home/runner/.ya/build/build_root/myn3 created=5 left=3 2024-11-20 08:07:51,237 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/myn3/STAMP 2024-11-20 08:07:51,250 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(kaKhVKkwv1kkbgok_0Mn2A$(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... opying file search/langs/tr.js COPY Copying file search/langs/vi.js COPY Copying file vendor.css COPY Copying file vendor.js COPY Copying file vendor.rtl.css 2024-11-20 08:07:51,250 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-20 08:07:51,250 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.005308866500854,"qty":1},"prepare_all_nodes":{"sum":0.008332014083862,"qty":1},"compact_cache":{"sum":0.001205921173096,"qty":1},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.319522619247436,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.485439300537109,"qty":2},"prepare[DO]":{"sum":0.000732898712158,"qty":1},"put_in_cache[DO]":{"sum":0.006108283996582,"qty":2},"pattern[YFM_TOOL-2315971726]":{"sum":7.658773183822632,"qty":1},"DO":{"sum":61.639307022094726,"qty":1},"result[DO]":{"sum":0.003346681594849,"qty":1},"write_through_caches[DO]":{"sum":0.081604719161987,"qty":1},"clean_build_root":{"sum":0.000329732894897,"qty":1}},"all":{"qty":14,"sum":70.21001124382019},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732090001.852699756622314,1732090009.511472940444946],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":69.415940284729004,"build_type":"release","flags":{"OPENSOURCE":"yes","USE_PREBUILT_TOOLS":"no","APPLE_SDK_LOCAL":"yes","USE_CLANG_CL":"yes","USE_AIO":"static","USE_ICONV":"static","USE_IDN":"static","CFLAGS":"-fno-omit-frame-pointer","USE_EAT_MY_DATA":"yes","DEBUGINFO_LINES_ONLY":"yes","CONSISTENT_DEBUG":"yes","TESTS_REQUESTED":"yes","DISABLE_FLAKE8_MIGRATIONS":"yes"},"rel_targets":["."],"threads":64} 2024-11-20 08:07:51,250 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-20 08:07:51,251 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732090071.2510116 2024-11-20 08:07:51,251 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732090071.2510116 2024-11-20 08:07:51,251 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732090071.2510116 2024-11-20 08:07:51,251 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732090071.2513733 2024-11-20 08:07:51,251 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732090071.2513733 2024-11-20 08:07:51,251 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732090071.2513733 2024-11-20 08:07:51,268 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-20 08:07:51,268 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-20 08:07:51,268 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,268 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,268 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-20 08:07:51,268 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-20 08:07:51,269 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-20 08:07:51,271 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-20 08:07:51,281 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,281 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,282 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,282 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 08:07:51,282 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732090071.2829092 2024-11-20 08:07:51,282 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732090071.2829092 2024-11-20 08:07:51,283 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732090071.2829092 2024-11-20 08:07:51,283 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-20 08:07:51,284 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-20 08:07:51,284 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-20 08:07:51,285 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 75902976 TotalSize: 75888592 2024-11-20 08:07:51,285 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732090071.2851274 2024-11-20 08:07:51,285 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732090071.2851274 2024-11-20 08:07:51,285 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732090071.2851274 2024-11-20 08:07:51,285 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 21. 2024-11-20 08:07:51,285 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 2. 2024-11-20 08:07:51,285 DEBUG (build.stat.statistics) [MainThread] Run tasks 3: 2 cached tasks (0 cache(s) resolved by dynamic uids), 1 not cached, 0 failed 2024-11-20 08:07:51,286 DEBUG (build.stat.statistics) [MainThread] Not cached 1: 0 tests, 1 not tests 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 66.66666666666667 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 3 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 3 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 2 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 1 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 1 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - 0 2024-11-20 08:07:51,286 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",61600,"local"]] 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 61600 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 61600 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 61600 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-20 08:07:51,287 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 61600 2024-11-20 08:07:51,288 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-20 08:07:51,288 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-20 08:07:51,288 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-20 08:07:51,288 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-20 08:07:51,288 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 61600 2024-11-20 08:07:51,289 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732090071.2891805 2024-11-20 08:07:51,289 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732090071.2891805 2024-11-20 08:07:51,289 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732090071.2891805 2024-11-20 08:07:51,289 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732090071.2898514 2024-11-20 08:07:51,289 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732090071.2898514 2024-11-20 08:07:51,289 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732090071.2898514 2024-11-20 08:07:51,290 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 2 entries 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 08:07:51,290 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-20 08:07:51,290 DEBUG (StoredReport) [MainThread] Finish report 2024-11-20 08:07:51,290 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 2.8133392333984375e-05}, 'BuildResultsListener': {'duration_sec': 0.0003528594970703125}} 2024-11-20 08:07:51,291 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-20 08:07:51,291 INFO (build.reports.build_reports) [MainThread] Dump junit report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/junit.xml 2024-11-20 08:07:51,291 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 08:07:51,291 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 08:07:51,291 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 08:07:51,291 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 08:07:51,291 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 08:07:51,291 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 08:07:51,291 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 08:07:51,292 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-20 08:07:51,292 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-20 08:07:51,292 INFO (build.reports.build_reports) [MainThread] Dump results report to /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/report.json 2024-11-20 08:07:51,292 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732090071.2928174 2024-11-20 08:07:51,292 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732090071.2928174 2024-11-20 08:07:51,292 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732090071.2928174 2024-11-20 08:07:51,293 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-20 08:07:51,293 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732090071.293769 2024-11-20 08:07:51,293 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732090071.293769 2024-11-20 08:07:51,293 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732090071.293769 2024-11-20 08:07:51,294 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732090071.2939978 2024-11-20 08:07:51,294 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732090071.2939978 2024-11-20 08:07:51,294 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732090071.2939978 2024-11-20 08:07:51,294 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732090071.2946508 2024-11-20 08:07:51,294 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732090071.2946508 2024-11-20 08:07:51,294 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732090071.2946508 2024-11-20 08:07:51,294 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732090071.2948575 2024-11-20 08:07:51,294 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732090071.2948575 2024-11-20 08:07:51,294 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732090071.2948575 2024-11-20 08:07:51,295 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732090071.295157 2024-11-20 08:07:51,295 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732090071.295157 2024-11-20 08:07:51,295 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732090071.295157 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000108 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000037 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000004 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000003 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000022 2024-11-20 08:07:51,295 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000003 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000002 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000009 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000002 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000005 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000010 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000017 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000010 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000002 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000001 2024-11-20 08:07:51,296 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys(['uid', 'file_log', 'display_in_memory_log', 'file_in_memory_log', 'revision', 'vcs_type', 'report', 'exit', 'lifecycle_ts', 'env_checker', 'fetchers_storage', 'tmp_dir']) 2024-11-20 08:07:51,297 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732090000.13089.pdkghe6p 2024-11-20 08:07:51,297 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-20 08:07:51,297 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000718 2024-11-20 08:07:51,297 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000012 2024-11-20 08:07:51,297 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000007 2024-11-20 08:07:51,297 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000007 2024-11-20 08:07:51,298 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000009 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001233 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000008 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000005 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000006 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000009 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000006 2024-11-20 08:07:51,299 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])