2024-11-20 16:36:40,861 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" initialized in 0.000015 2024-11-20 16:36:40,862 DEBUG (root) [MainThread] Release revision: 15278243 2024-11-20 16:36:40,862 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" initialized in 0.000792 2024-11-20 16:36:40,862 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 16:36:40,862 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 16:36:40,862 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 16:36:40,862 DEBUG (root) [MainThread] vcs type: git 2024-11-20 16:36:40,862 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000208 2024-11-20 16:36:40,862 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,862 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" initialized in 0.000423 2024-11-20 16:36:40,863 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" initialized in 0.000002 2024-11-20 16:36:40,863 DEBUG (root) [MainThread] Start up timestamp 2024-11-20T16:36:40.UTC (2024-11-20T16:36:40.UTC) 2024-11-20 16:36:40,863 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" initialized in 0.000036 2024-11-20 16:36:40,863 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" initialized in 0.000005 2024-11-20 16:36:40,863 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000419 2024-11-20 16:36:40,863 DEBUG (library.python.tmp) [MainThread] Set TMPDIR=/home/runner/.ya/tmp/1732120600.2963.pf7n3429 instead of None 2024-11-20 16:36:40,863 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" initialized in 0.000135 2024-11-20 16:36:40,863 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-early, group=overall-execution, time=1732120600.8638706 2024-11-20 16:36:40,863 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-early_finished=1732120600.8638706 2024-11-20 16:36:40,863 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-early_finished - 1732120600.8638706 2024-11-20 16:36:40,864 DEBUG (core.stage_tracer) [MainThread] Start stage tag=handler-selection, group=overall-execution, time=1732120600.8643696 2024-11-20 16:36:40,864 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_started=1732120600.8643696 2024-11-20 16:36:40,864 DEBUG (core.profiler) [MainThread] Profile step handler-selection_started - 1732120600.8643696 2024-11-20 16:36:40,864 DEBUG (core.plugin_loader) [MainThread] Load handlers.make 2024-11-20 16:36:40,877 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/ya.conf 2024-11-20 16:36:40,877 DEBUG (core.yarg.config_files) [MainThread] Found 21 keys 2024-11-20 16:36:40,877 DEBUG (core.yarg.populate) [MainThread] Use config file `/home/runner/actions_runner/_work/ydb/ydb/ya.conf` with `21` keys 2024-11-20 16:36:40,877 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 16:36:40,878 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `incremental_build_dirs_cleanup` 2024-11-20 16:36:40,878 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `use_atd_revisions_info` 2024-11-20 16:36:40,878 DEBUG (core.yarg.config_files) [MainThread] Load data from config /home/runner/actions_runner/_work/ydb/ydb/build/internal/ya.conf 2024-11-20 16:36:40,879 DEBUG (core.yarg.config_files) [MainThread] Found 9 keys 2024-11-20 16:36:40,879 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 16:36:40,879 DEBUG (core.yarg.populate) [MainThread] No aliases to inject 2024-11-20 16:36:40,879 DEBUG (core.yarg.config_files) [MainThread] Unknown config parameter `bazel_remote_client_decompress` 2024-11-20 16:36:40,890 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 16:36:40,890 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 16:36:40,891 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=handler-selection, group=overall-execution, time=1732120600.891663 2024-11-20 16:36:40,891 DEBUG (core.stages_profiler) [MainThread] Set stage handler-selection_finished=1732120600.891663 2024-11-20 16:36:40,891 DEBUG (core.profiler) [MainThread] Profile step handler-selection_finished - 1732120600.891663 2024-11-20 16:36:40,891 DEBUG (core.stage_tracer) [MainThread] Start stage tag=modules-initialization-full, group=overall-execution, time=1732120600.8917916 2024-11-20 16:36:40,891 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_started=1732120600.8917916 2024-11-20 16:36:40,891 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_started - 1732120600.8917916 2024-11-20 16:36:40,892 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 16:36:40,892 DEBUG (core.respawn) [MainThread] Same as prev source root /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 16:36:40,892 DEBUG (core.respawn.pyver) [MainThread] No need to respawn to other ya-bin version 2024-11-20 16:36:40,892 DEBUG (core.respawn) [MainThread] Unsetting YA_STDIN env var as respawn never happened 2024-11-20 16:36:40,892 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" initialized in 0.000293 2024-11-20 16:36:40,894 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,894 DEBUG (root) [MainThread] Update replacements in 2024-11-20 16:36:40,894 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 16:36:40,894 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,894 DEBUG (root) [MainThread] Add suppressing formatter to 2024-11-20 16:36:40,894 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,894 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" initialized in 0.002413 2024-11-20 16:36:40,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" initialized in 0.000236 2024-11-20 16:36:40,895 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" initialized in 0.000050 2024-11-20 16:36:40,895 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,896 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" initialized in 0.000957 2024-11-20 16:36:40,896 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" initialized in 0.000035 2024-11-20 16:36:40,896 DEBUG (yalibrary.vcs) [MainThread] detecting vcs from /home/runner/actions_runner/_work/ydb/ydb for paths: None 2024-11-20 16:36:40,896 DEBUG (yalibrary.vcs) [MainThread] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 16:36:40,896 DEBUG (yalibrary.vcs) [MainThread] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 16:36:40,896 DEBUG (root) [MainThread] vcs type: git 2024-11-20 16:36:40,896 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" initialized in 0.000226 2024-11-20 16:36:40,896 DEBUG (devtools.ya.app) [MainThread] origin: github 2024-11-20 16:36:40,896 DEBUG (devtools.ya.app) [MainThread] python: sys.version_info(major=3, minor=12, micro=7, releaselevel='final', serial=0) 2024-11-20 16:36:40,896 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" initialized in 0.000103 2024-11-20 16:36:40,896 DEBUG (root) [MainThread] Custom fetcher: None, fetcher parameters: [{'name': 'custom'}, {'name': 'proxy'}, {'name': 'skynet'}, {'name': 'mds'}, {'name': 'sandbox'}], sb_token none 2024-11-20 16:36:40,897 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" initialized in 0.000078 2024-11-20 16:36:40,897 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" initialized in 0.000002 2024-11-20 16:36:40,897 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" initialized in 0.000001 2024-11-20 16:36:40,898 DEBUG (devtools.ya.core.sec) [MainThread] Found 0 secrets 2024-11-20 16:36:40,898 DEBUG (root) [MainThread] Update replacements in 2024-11-20 16:36:40,898 DEBUG (root) [MainThread] Update replacements in 2024-11-20 16:36:40,898 DEBUG (root) [MainThread] Update replacements in DisplayStreamHandler 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" initialized in 0.001144 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" initialized in 0.000064 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" initialized in 0.000002 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" initialized in 0.000143 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" initialized in 0.000002 2024-11-20 16:36:40,898 DEBUG (devtools.ya.app) [MainThread] mlockall return code: 12 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" initialized in 0.000039 2024-11-20 16:36:40,898 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" initialized in 0.000005 2024-11-20 16:36:40,899 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" initialized in 0.000245 2024-11-20 16:36:40,899 DEBUG (root) [MainThread] Event log file is /home/runner/actions_runner/_work/ydb/ydb/tmp/results/try_1/ya_evlog.jsonl 2024-11-20 16:36:40,899 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" initialized in 0.000443 2024-11-20 16:36:40,941 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" initialized in 0.041360 2024-11-20 16:36:40,941 DEBUG (devtools.ya.app) [MainThread] Run action on with params 2024-11-20 16:36:40,941 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=modules-initialization-full, group=overall-execution, time=1732120600.941876 2024-11-20 16:36:40,941 DEBUG (core.stages_profiler) [MainThread] Set stage modules-initialization-full_finished=1732120600.941876 2024-11-20 16:36:40,941 DEBUG (core.profiler) [MainThread] Profile step modules-initialization-full_finished - 1732120600.941876 2024-11-20 16:36:40,942 DEBUG (core.stage_tracer) [MainThread] Start stage tag=invoke-do_ya_make, group=overall-execution, time=1732120600.9420497 2024-11-20 16:36:40,942 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_started=1732120600.9420497 2024-11-20 16:36:40,942 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_started - 1732120600.9420497 2024-11-20 16:36:41,078 DEBUG (core.stage_tracer) [MainThread] Start stage tag=ya_make_handler, group=build_handler, time=1732120601.0781395 2024-11-20 16:36:41,078 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_started=1732120601.0781395 2024-11-20 16:36:41,078 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_started - 1732120601.0781395 2024-11-20 16:36:41,078 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_generating, group=build_handler, time=1732120601.0783927 2024-11-20 16:36:41,078 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_started=1732120601.0783927 2024-11-20 16:36:41,078 DEBUG (core.profiler) [MainThread] Profile step context_generating_started - 1732120601.0783927 2024-11-20 16:36:41,078 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_cache_configuration, group=build_handler, time=1732120601.0785193 2024-11-20 16:36:41,078 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_started=1732120601.0785193 2024-11-20 16:36:41,078 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_started - 1732120601.0785193 2024-11-20 16:36:41,078 DEBUG (build.ya_make) [MainThread] Build graph cache processing started 2024-11-20 16:36:41,078 DEBUG (build.ya_make) [MainThread] Build graph cache processing disabled 2024-11-20 16:36:41,078 DEBUG (build.ya_make) [MainThread] Build graph cache is not available in opensource 2024-11-20 16:36:41,078 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_cache_configuration, group=build_handler, time=1732120601.0787344 2024-11-20 16:36:41,078 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_cache_configuration_finished=1732120601.0787344 2024-11-20 16:36:41,078 DEBUG (core.profiler) [MainThread] Profile step build_graph_cache_configuration_finished - 1732120601.0787344 2024-11-20 16:36:41,078 DEBUG (core.stage_tracer) [MainThread] Start stage tag=context_creation, group=ya_make, time=1732120601.0789878 2024-11-20 16:36:41,079 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_started=1732120601.0789878 2024-11-20 16:36:41,079 DEBUG (core.profiler) [MainThread] Profile step context_creation_started - 1732120601.0789878 2024-11-20 16:36:41,081 DEBUG (yalibrary.toolscache) [MainThread] Tools cache uses passed opts 2024-11-20 16:36:41,081 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 16:36:41,081 DEBUG (build.ya_make) [MainThread] content UIDs enabled by request 2024-11-20 16:36:41,084 DEBUG (build.ya_make) [Misc-00] Using '/tmp/tmp.X3nEU90CvE' file to obtain bazel remote password 2024-11-20 16:36:41,084 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build_graph_and_tests, group=graph, time=1732120601.0841613 2024-11-20 16:36:41,084 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_started=1732120601.0841613 2024-11-20 16:36:41,084 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_started - 1732120601.0841613 2024-11-20 16:36:41,084 DEBUG (build.graph) [Misc-01] Collect vcs info 2024-11-20 16:36:41,085 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 16:36:41,085 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ya.conf.json 2024-11-20 16:36:41,085 DEBUG (yalibrary.vcs) [Misc-01] common root: /home/runner/actions_runner/_work/ydb/ydb 2024-11-20 16:36:41,086 DEBUG (yalibrary.vcs) [Misc-01] vcs root: /home/runner/actions_runner/_work/ydb/ydb (git) 2024-11-20 16:36:41,086 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang16.json 2024-11-20 16:36:41,088 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/platform/clang/clang18.json 2024-11-20 16:36:41,091 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 16:36:41,091 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 16:36:41,091 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 16:36:41,091 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ymake 2024-11-20 16:36:41,091 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/ymake/public.resources.json 2024-11-20 16:36:41,092 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 16:36:41,092 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 16:36:41,092 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 16:36:41,092 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/mapping.conf.json 2024-11-20 16:36:41,094 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/ext_mapping.conf.json 2024-11-20 16:36:41,103 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for resource 7448917907) 2024-11-20 16:36:41,103 DEBUG (yalibrary.toolscache) [MainThread] Tools cache server resource download/parameters 2024-11-20 16:36:41,103 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 16:36:41,103 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 16:36:41,103 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 16:36:41,103 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: will use 'linux' platform 2024-11-20 16:36:41,103 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 16:36:41,104 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 16:36:41,104 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/6812260917 2024-11-20 16:36:41,104 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ya-tc: successfully fetched into /home/runner/.ya/tools/v4/6812260917 2024-11-20 16:36:41,104 DEBUG (yalibrary.tools) [MainThread] Using old-style toolchain for: ya-tc 2024-11-20 16:36:41,104 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: None 2024-11-20 16:36:41,104 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 16:36:41,108 DEBUG (yalibrary.toolscache) [MainThread] Tools cache (request for resource 7448917907) address: (2377, 20232, 'unix:/home/runner/.ya/tools/v4/psinguToHLV.tmp') 2024-11-20 16:36:41,113 DEBUG (yalibrary.toolscache) [MainThread] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 16:36:41,113 DEBUG (yalibrary.fetcher.cache_helper) [MainThread] Resource seems to be installed in /home/runner/.ya/tools/v4/7448917907 2024-11-20 16:36:41,113 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: successfully fetched into /home/runner/.ya/tools/v4/7448917907 2024-11-20 16:36:41,113 DEBUG (exts.timer) [MainThread] Timer build.graph, stage fetch ymake: 0.022268056869506836 2024-11-20 16:36:41,114 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.1143885 2024-11-20 16:36:41,114 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_started=1732120601.1143885 2024-11-20 16:36:41,114 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_started - 1732120601.1143885 2024-11-20 16:36:41,115 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.1147966 2024-11-20 16:36:41,115 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_started=1732120601.1147966 2024-11-20 16:36:41,115 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_started - 1732120601.1147966 2024-11-20 16:36:41,115 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-tools, group=graph, time=1732120601.1149182 2024-11-20 16:36:41,116 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_started=1732120601.1149182 2024-11-20 16:36:41,116 DEBUG (core.profiler) [MainThread] Profile step get-tools_started - 1732120601.1149182 2024-11-20 16:36:41,116 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.1153936 2024-11-20 16:36:41,116 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started=1732120601.1153936 2024-11-20 16:36:41,116 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_started - 1732120601.1153936 2024-11-20 16:36:41,116 DEBUG (yalibrary.guards) [Misc-02] Enter guard fetch with value None 2024-11-20 16:36:41,116 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.1159315 2024-11-20 16:36:41,116 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started=1732120601.1159315 2024-11-20 16:36:41,116 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_started - 1732120601.1159315 2024-11-20 16:36:41,116 DEBUG (yalibrary.guards) [Misc-03] Enter guard fetch with value None 2024-11-20 16:36:41,117 DEBUG (core.stage_tracer) [MainThread] Start stage tag=waiting-tool-targets, group=graph, time=1732120601.116151 2024-11-20 16:36:41,117 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_started=1732120601.116151 2024-11-20 16:36:41,117 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_started - 1732120601.116151 2024-11-20 16:36:41,117 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --tags HEAD failed with exit code 128 2024-11-20 16:36:41,120 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] b'fatal: No names found, cannot describe anything.\n' Running git describe --exact-match --all HEAD failed with exit code 128 2024-11-20 16:36:41,122 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Git info commit:b'79055f4e470d2f0aec69d9b78399027011586dab', author:b'Natasha ', summary:b'Merge 03315766a95b346d6d19b68da634fc5d470287ff into 4644e090d713dd56dd5734b49ad80400f1a21414', svn_id:b'' 2024-11-20 16:36:41,123 DEBUG (yalibrary.vcs.vcsversion) [Misc-01] Other data: Other info: Build by: runner Top src dir: /home/runner/actions_runner/_work/ydb/ydb Top build dir: /home/runner/.ya/build Hostname: ghrun-e5fffkocce Host information: Linux ghrun-e5fffkocce 5.15.0-124-generic #134-Ubuntu SMP Fri Sep 27 20:20:17 UTC 2024 x86_64 2024-11-20 16:36:41,123 DEBUG (build.graph) [Misc-01] Got version json "{\n \"ARCADIA_PATCH_NUMBER\": 1,\n \"ARCADIA_SOURCE_HG_HASH\": \"79055f4e470d2f0aec69d9b78399027011586dab\",\n \"ARCADIA_SOURCE_LAST_AUTHOR\": \"Natasha \",\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-20T16:36:41.000000Z\",\n \"BUILD_HOST\": \"ghrun-e5fffkocce\",\n \"BUILD_TIMESTAMP\": 1732120601,\n \"BUILD_USER\": \"runner\",\n \"CUSTOM_VERSION\": \"\",\n \"DIRTY\": \"\",\n \"PROGRAM_VERSION\": \"Git info:\\n Commit: 79055f4e470d2f0aec69d9b78399027011586dab\\n Branch: \\n Author: Natasha \\n Summary: Merge 03315766a95b346d6d19b68da634fc5d470287ff into 4644e090d713dd56dd5734b49ad80400f1a21414\\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-e5fffkocce\\n Host information: \\n Linux ghrun-e5fffkocce 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: 79055f4e470d2f0aec69d9b78399027011586dab\\n Branch: \\n Author: Natasha \\n Summary: Merge 03315766a95b346d6d19b68da634fc5d470287ff into 4644e090d713dd56dd5734b49ad80400f1a21414\\n\",\n \"VCS\": \"git\"\n}" 2024-11-20 16:36:41,123 DEBUG (exts.timer) [Misc-01] Timer get_version_info, stage vcs info: 0.03834390640258789 2024-11-20 16:36:41,153 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 16:36:41,154 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 16:36:41,293 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 16:36:41,294 DEBUG (yalibrary.guards) [Misc-02] Exit guard fetch with value None 2024-11-20 16:36:41,294 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.29417 2024-11-20 16:36:41,294 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished=1732120601.29417 2024-11-20 16:36:41,294 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-pic_finished - 1732120601.29417 2024-11-20 16:36:41,294 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.2943852 2024-11-20 16:36:41,294 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_started=1732120601.2943852 2024-11-20 16:36:41,294 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_started - 1732120601.2943852 2024-11-20 16:36:41,294 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 0x7f1493c5e840>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.30a8vghk/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.30a8vghk/makefiles.dart'} 2024-11-20 16:36:41,294 DEBUG (core.yarg.behaviour) [Misc-02] Found unused args ['grab_stderr'] 2024-11-20 16:36:41,294 DEBUG (build.prefetch) [Misc-02] prefetch disabled 2024-11-20 16:36:41,295 DEBUG (core.event_handling.base_subscriber) [Misc-02] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 16:36:41,295 DEBUG (yalibrary.tools) [Misc-02] Using old-style toolchain for: ymake 2024-11-20 16:36:41,295 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/1732120600.2963.pf7n3429/yatmpbyoxarcb --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/1732120600.2963.pf7n3429/gen_graph.30a8vghk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.30a8vghk/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 16:36:41,295 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 16:36:41,298 DEBUG (yalibrary.guards) [Misc-03] Exit guard fetch with value None 2024-11-20 16:36:41,298 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.2982783 2024-11-20 16:36:41,298 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished=1732120601.2982783 2024-11-20 16:36:41,298 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-gen-opts-default-linux-x86_64-release-asan-nopic_finished - 1732120601.2982783 2024-11-20 16:36:41,298 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.2985592 2024-11-20 16:36:41,298 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_started=1732120601.2985592 2024-11-20 16:36:41,298 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_started - 1732120601.2985592 2024-11-20 16:36:41,298 DEBUG (build.ymake2) [Misc-03] Run gen graph with {'grab_stderr': True, 'mode': 'dist', 'dump_graph': 'json', 'check': True, '_purpose': 'default-linux-x86_64-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 0x7f1493c5f4c0>, 'enabled_events': 'dGT', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/makefiles.dart'} 2024-11-20 16:36:41,298 DEBUG (core.yarg.behaviour) [Misc-03] Found unused args ['grab_stderr'] 2024-11-20 16:36:41,299 DEBUG (build.prefetch) [Misc-03] prefetch disabled 2024-11-20 16:36:41,299 DEBUG (yalibrary.tools) [Misc-03] Using old-style toolchain for: ymake 2024-11-20 16:36:41,299 DEBUG (build.ymake2.run_ymake) [Misc-03] run: '/home/runner/.ya/tools/v4/7448917907/ymake --depends-like-recurse --warn dirloops,ChkPeers --write-meta-data /home/runner/.ya/tmp/1732120600.2963.pf7n3429/yatmpwpfhb1ed --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/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' with env: { } 2024-11-20 16:36:41,302 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732120601302125,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":0} 2024-11-20 16:36:41,302 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601302237,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,302 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732120601302275,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,303 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601302285,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,303 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration from cache","_timestamp":1732120601302289,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,303 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732120601302563,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,303 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732120601302569,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,305 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732120601305454,"_typename":"NEvent.TStageStarted","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":1} 2024-11-20 16:36:41,305 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Target path: /home/runner/actions_runner/_work/ydb/ydb\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601305565,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,305 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732120601305610,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,306 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Conf cache has not been loaded (reason: File does not exist)...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601305620,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,306 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration from cache","_timestamp":1732120601305624,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,306 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedConfCache","Type":"bool","_timestamp":1732120601305897,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,306 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732120601305902,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,470 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732120601469904,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,470 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732120601469927,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,471 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732120601471607,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,471 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732120601471619,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,472 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fold global commands","_timestamp":1732120601472913,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,473 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732120601472922,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,474 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load configuration (no cache)","_timestamp":1732120601474800,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,475 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732120601474819,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,476 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Compile and RecalcAll conditions","_timestamp":1732120601476352,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,476 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732120601476365,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,477 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fold global commands","_timestamp":1732120601477674,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,477 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732120601477682,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,599 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Load plugins","_timestamp":1732120601598892,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,604 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Load plugins","_timestamp":1732120601603756,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,632 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632550,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,632 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632578,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632583,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632686,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732120601632691,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632767,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632771,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,633 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632775,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632779,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632782,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632786,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632789,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601632793,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"HasChangelist":false,"_timestamp":1732120601633737,"_typename":"NEvent.TArcChanges","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601633746,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732120601633755,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,634 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732120601633759,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,635 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732120601633765,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,635 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601633771,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,635 DEBUG (build.ya_make) [Misc-02] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732120601633776,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":0} 2024-11-20 16:36:41,635 DEBUG (build.ya_make) [Misc-02] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732120601633780,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":0} 2024-11-20 16:36:41,635 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732120601633784,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,637 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"MAIN_OUTPUT_AS_EXTRA enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637407,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,637 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_DEDICATED_MODULE_NODE disabled (by default)\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637429,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"YMAKE_CHECK_FOR_INCORRECT_LOOPS disabled (by default)\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637434,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"mlockall failed\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637516,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732120601637521,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache loading is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637578,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache saving is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637590,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache loading is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637595,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache saving is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637599,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,638 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache loading is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637604,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DepManagement cache saving is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637608,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache loading is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637612,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache saving is enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601637615,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"HasChangelist":false,"_timestamp":1732120601638559,"_typename":"NEvent.TArcChanges","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because of PatchPath\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601638568,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedFSCache","Type":"bool","_timestamp":1732120601638576,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedDepsCache","Type":"bool","_timestamp":1732120601638582,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,639 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732120601638586,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,640 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Graph has structural changes because start dirs are different\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601638592,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,640 DEBUG (build.ya_make) [Misc-03] Configure message {"PredictsStructuralChanges":true,"_timestamp":1732120601638597,"_typename":"NEvent.TGraphChangesPrediction","ymake_run_uid":1} 2024-11-20 16:36:41,640 DEBUG (build.ya_make) [Misc-03] Configure message {"Enabled":false,"MaybeEnabled":false,"_timestamp":1732120601638601,"_typename":"NEvent.TBypassConfigure","ymake_run_uid":1} 2024-11-20 16:36:41,640 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732120601638605,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,734 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.1008150151,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732120601734611,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Configure graph","_timestamp":1732120601734638,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601734648,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601734655,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732120601734709,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,735 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":3066,"Sub":"","Type":"Debug","_timestamp":1732120601734790,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find Lost Includes","_timestamp":1732120601734793,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,735 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"loaded size = 9020005; load time = 10727; loaded MD5 time = 805; Max loaded MD5 time = 5; loaded count = 2174; max load time = 200; mapped size = 40632376; mapped MD5 time = 7670; Max mapped MD5 time = 492; mapped count = 130; map time = 747; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3391; lstat min us = 1; lstat avr us = 1; lstat max us = 16; opendir count = 388; opendir sum us = 0; opendir min us = 0; opendir avr us = 0; opendir max us = 0; readdir count = 2837; readdir sum us = 2250; readdir min us = 0; readdir avr us = 0; readdir max us = 44; (opendir + readdir) sum us = 2250; (lstat + opendir + readdir) sum us = 5641;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732120601734829,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-02] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2250,"LoadTime":10727,"LoadedCount":2174,"LoadedMD5Time":805,"LoadedSize":9020005,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5641,"LstatMaxUs":16,"LstatMinUs":1,"LstatSumUs":3391,"MapTime":747,"MappedCount":130,"MappedMD5Time":7670,"MappedSize":40632376,"MaxLoadTime":200,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":492,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":44,"ReaddirMinUs":0,"ReaddirSumUs":2250,"_timestamp":1732120601734843,"_typename":"NEvent.TFileConfStats","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":9020005,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732120601734854,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":10727,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732120601734858,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732120601734862,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732120601734865,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,736 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.09795793717,"Name":"EYmakeStats::ConfigureGraphTime","Type":"double","_timestamp":1732120601736572,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":747,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732120601734867,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Configure graph","_timestamp":1732120601736592,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732120601734870,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Searching target '.'\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601736600,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732120601734873,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Selected target '$S' with id 3\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601736607,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"bucket id = 0; loaded size = 9020005; load time = 10727; loaded count = 2174; max load time = 200;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732120601734881,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732120601736655,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,737 DEBUG (build.ya_make) [Misc-02] Configure message {"BucketId":0,"LoadTime":10727,"LoadedCount":2174,"LoadedSize":9020005,"MaxLoadTime":200,"_timestamp":1732120601734886,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":0} 2024-11-20 16:36:41,738 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":3068,"Sub":"","Type":"Debug","_timestamp":1732120601736739,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,738 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"parse time = 3; parsed files count = 6; parsed files size = 12965; parsed files recovered = 0; .in files count = 0; .in files size = 0;","Mod":"unimp","Sub":"Parsing stats","Type":"Debug","_timestamp":1732120601734897,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,738 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find Lost Includes","_timestamp":1732120601736743,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,738 DEBUG (build.ya_make) [Misc-02] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":3,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732120601734902,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":0} 2024-11-20 16:36:41,738 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":1732120601734914,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,738 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"loaded size = 9020005; load time = 9920; loaded MD5 time = 834; Max loaded MD5 time = 5; loaded count = 2174; max load time = 21; mapped size = 40632376; mapped MD5 time = 6661; Max mapped MD5 time = 421; mapped count = 130; map time = 717; from patch count = 0; from patch size = 0; file stat count = 3223; lstat count = 3045; lstat sum us = 3119; lstat min us = 0; lstat avr us = 1; lstat max us = 23; 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 = 2046; readdir min us = 0; readdir avr us = 0; readdir max us = 42; (opendir + readdir) sum us = 2046; (lstat + opendir + readdir) sum us = 5165;","Mod":"unimp","Sub":"File access stats","Type":"Debug","_timestamp":1732120601736777,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,738 DEBUG (build.ya_make) [Misc-02] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732120601734918,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":0} 2024-11-20 16:36:41,738 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":1732120601734934,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-03] Configure message {"FileStatCount":3223,"FromPatchCount":0,"FromPatchSize":0,"ListDirSumUs":2046,"LoadTime":9920,"LoadedCount":2174,"LoadedMD5Time":834,"LoadedSize":9020005,"LstatAvrUs":1,"LstatCount":3045,"LstatListDirSumUs":5165,"LstatMaxUs":23,"LstatMinUs":0,"LstatSumUs":3119,"MapTime":717,"MappedCount":130,"MappedMD5Time":6661,"MappedSize":40632376,"MaxLoadTime":21,"MaxLoadedMD5Time":5,"MaxMappedMD5Time":421,"OpendirAvrUs":0,"OpendirCount":388,"OpendirMaxUs":0,"OpendirMinUs":0,"OpendirSumUs":0,"ReaddirAvrUs":0,"ReaddirCount":2837,"ReaddirMaxUs":42,"ReaddirMinUs":0,"ReaddirSumUs":2046,"_timestamp":1732120601736790,"_typename":"NEvent.TFileConfStats","ymake_run_uid":1} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-02] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732120601734938,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":0} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9020005,"Name":"EFileConfStats::LoadedSize","Type":"int","_timestamp":1732120601736803,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732120601734944,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":9920,"Name":"EFileConfStats::LoadTime","Type":"int","_timestamp":1732120601736807,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-02] Configure message {"NukedDir":0,"_timestamp":1732120601734947,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":0} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2174,"Name":"EFileConfStats::LoadedCount","Type":"int","_timestamp":1732120601736811,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,739 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":1732120601734954,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,739 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":40632376,"Name":"EFileConfStats::MappedSize","Type":"int","_timestamp":1732120601736814,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-02] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732120601734959,"_typename":"NEvent.TResolveStats","ymake_run_uid":0} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":717,"Name":"EFileConfStats::MapTime","Type":"int","_timestamp":1732120601736816,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Build graph","_timestamp":1732120601734962,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":130,"Name":"EFileConfStats::MappedCount","Type":"int","_timestamp":1732120601736819,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,740 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":1732120601735061,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":2304,"Name":"EFileConfStats::Count","Type":"int","_timestamp":1732120601736822,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-02] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732120601735066,"_typename":"NEvent.TModulesStats","ymake_run_uid":0} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"bucket id = 0; loaded size = 9020005; load time = 9920; loaded count = 2174; max load time = 21;","Mod":"unimp","Sub":"Bucket #0","Type":"Debug","_timestamp":1732120601736830,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,740 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732120601735070,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-03] Configure message {"BucketId":0,"LoadTime":9920,"LoadedCount":2174,"LoadedSize":9020005,"MaxLoadTime":21,"_timestamp":1732120601736835,"_typename":"NEvent.TFileConfSubStats","ymake_run_uid":1} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732120601735073,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,741 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":1732120601736846,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732120601735076,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-03] Configure message {"InFilesCount":0,"InFilesSize":0,"ParseTime":4,"ParsedFilesCount":6,"ParsedFilesRecovered":0,"ParsedFilesSize":12965,"_timestamp":1732120601736851,"_typename":"NEvent.TIncParserManagerStats","ymake_run_uid":1} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-02] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732120601735079,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,741 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":1732120601736863,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-03] Configure message {"Count":8,"Includes":12,"Size":1264,"UniqueCount":8,"UniqueSize":853,"_timestamp":1732120601736868,"_typename":"NEvent.TGeneralParserStats","ymake_run_uid":1} 2024-11-20 16:36:41,741 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732120601735088,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,742 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":1732120601736885,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Set reachable nodes","_timestamp":1732120601735696,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-03] Configure message {"CommandsCount":142,"EdgesCount":12363,"FilesCount":3263,"NodesCount":2825,"_timestamp":1732120601736889,"_typename":"NEvent.TDepGraphStats","ymake_run_uid":1} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732120601735698,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"nuke mod dir = 0;","Mod":"unimp","Sub":"UpdIter stats","Type":"Debug","_timestamp":1732120601736894,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Sort edges","_timestamp":1732120601738413,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-03] Configure message {"NukedDir":0,"_timestamp":1732120601736898,"_typename":"NEvent.TUpdIterStats","ymake_run_uid":1} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732120601738416,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,742 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":1732120601736905,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,742 DEBUG (build.ya_make) [Misc-03] Configure message {"IncludesAttempted":0,"IncludesFromCache":0,"ResolveAsKnownFromCache":0,"ResolveAsKnownTotal":0,"_timestamp":1732120601736909,"_typename":"NEvent.TResolveStats","ymake_run_uid":1} 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732120601738466,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,743 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=0 (default-linux-x86_64-release-asan-pic). Tool targets: set() 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Build graph","_timestamp":1732120601736913,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732120601738468,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,743 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":1732120601737032,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check blacklist","_timestamp":1732120601738471,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-03] Configure message {"Accessed":19,"Loaded":0,"Outdated":0,"Parsed":19,"Total":19,"_timestamp":1732120601737037,"_typename":"NEvent.TModulesStats","ymake_run_uid":1} 2024-11-20 16:36:41,743 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732120601738473,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Accessed","Type":"int","_timestamp":1732120601737041,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check isolated projects","_timestamp":1732120601738476,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Loaded","Type":"int","_timestamp":1732120601737045,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732120601738478,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":0,"Name":"EModulesStats::Outdated","Type":"int","_timestamp":1732120601737048,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Report Configure Events","_timestamp":1732120601738535,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-03] Configure message {"IntValue":19,"Name":"EModulesStats::Parsed","Type":"int","_timestamp":1732120601737051,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732120601738537,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,744 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732120601737061,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,745 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":1732120601739525,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Set reachable nodes","_timestamp":1732120601737663,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,745 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":1732120601739536,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":0} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732120601737666,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601739544,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Sort edges","_timestamp":1732120601740467,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601739550,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732120601740471,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,745 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save and compact","_timestamp":1732120601740825,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,746 DEBUG (_ToolTargetsQueue) [MainThread] Source_id=1 (default-linux-x86_64-release-asan-nopic). Tool targets: set() 2024-11-20 16:36:41,746 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=waiting-tool-targets, group=graph, time=1732120601.7461197 2024-11-20 16:36:41,746 DEBUG (core.stages_profiler) [MainThread] Set stage waiting-tool-targets_finished=1732120601.7461197 2024-11-20 16:36:41,746 DEBUG (core.profiler) [MainThread] Profile step waiting-tool-targets_finished - 1732120601.7461197 2024-11-20 16:36:41,746 DEBUG (build.graph) [MainThread] Empty tool targets list 2024-11-20 16:36:41,746 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-tools, group=graph, time=1732120601.7464726 2024-11-20 16:36:41,746 DEBUG (core.stages_profiler) [MainThread] Set stage get-tools_finished=1732120601.7464726 2024-11-20 16:36:41,746 DEBUG (core.profiler) [MainThread] Profile step get-tools_finished - 1732120601.7464726 2024-11-20 16:36:41,746 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732120601.746688 2024-11-20 16:36:41,746 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732120601.746688 2024-11-20 16:36:41,746 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732120601.746688 2024-11-20 16:36:41,746 DEBUG (core.stage_tracer) [MainThread] Start stage tag=wait-target-graphs-1, group=graph, time=1732120601.746917 2024-11-20 16:36:41,746 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_started=1732120601.746917 2024-11-20 16:36:41,747 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_started - 1732120601.746917 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732120601740832,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Foreign Platform Events","_timestamp":1732120601740524,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-02] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732120601741286,"_typename":"NEvent.TGraphChanges","ymake_run_uid":0} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732120601740526,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Detect loops","_timestamp":1732120601741320,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check blacklist","_timestamp":1732120601740528,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732120601741322,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732120601740531,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,747 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732120601741826,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check isolated projects","_timestamp":1732120601740533,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601741976,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732120601740536,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732120601741982,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Report Configure Events","_timestamp":1732120601740596,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732120601741984,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732120601740598,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601741988,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,748 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":1732120601741553,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,748 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732120601742023,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,749 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":1732120601741564,"_typename":"NEvent.TInternalCacheSaverStats","ymake_run_uid":1} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Fill package outputs","_timestamp":1732120601742026,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been saved...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601741572,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732120601742030,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been saved...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601741578,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732120601742183,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save and compact","_timestamp":1732120601742714,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732120601742207,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,749 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732120601742720,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732120601742926,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-03] Configure message {"HasContentChanges":true,"HasStructuralChanges":true,"_timestamp":1732120601743179,"_typename":"NEvent.TGraphChanges","ymake_run_uid":1} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732120601742985,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Detect loops","_timestamp":1732120601743213,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732120601742990,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732120601743215,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601742995,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732120601743819,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732120601742999,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,750 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been saved...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601743975,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Visit JSON","_timestamp":1732120601746320,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Dependency management cache","_timestamp":1732120601743980,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732120601746326,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Apply Dependency Management","_timestamp":1732120601743983,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601746339,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Modules requiring dependency management: 0; BFS traversals performed: 0\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601743986,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732120601744025,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,751 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Fill package outputs","_timestamp":1732120601744027,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732120601744031,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Check Transitive Requirements","_timestamp":1732120601744183,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732120601744205,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Find missing peerdirs","_timestamp":1732120601744915,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732120601744975,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732120601744981,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Exporting JSON\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601744985,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732120601744990,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Visit JSON","_timestamp":1732120601748331,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,752 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732120601748337,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,753 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Store inputs in JSON cache: enabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601748349,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,754 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":1732120601754476,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,755 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":1732120601754484,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601754493,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.0081718969,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732120601754503,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Render JSON","_timestamp":1732120601754508,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732120601754511,"_typename":"NEvent.TStageStarted","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601755313,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Save Uids cache","_timestamp":1732120601755319,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,755 DEBUG (build.ya_make) [Misc-02] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732120601755332,"_typename":"NEvent.TNodeChanges","ymake_run_uid":0} 2024-11-20 16:36:41,756 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":1732120601755345,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,756 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":1732120601755351,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":0} 2024-11-20 16:36:41,756 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.01244760211,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732120601755442,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,756 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Write JSON","_timestamp":1732120601755447,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,756 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"Export JSON","_timestamp":1732120601755470,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,756 DEBUG (build.ya_make) [Misc-02] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732120601755474,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,756 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":1732120601756457,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,757 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":1732120601755483,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,757 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":1732120601756466,"_typename":"NEvent.TJsonCacheStats","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-02] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732120601755488,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":0} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"JSON cache report: disabled\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601756475,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.008143028053,"Name":"EYmakeStats::RenderJSONTime","Type":"double","_timestamp":1732120601756484,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Render JSON","_timestamp":1732120601756489,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732120601756492,"_typename":"NEvent.TStageStarted","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been saved...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601757380,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,758 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601758798,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,759 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Save Uids cache","_timestamp":1732120601757387,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,759 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601758814,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,759 DEBUG (build.ya_make) [Misc-03] Configure message {"HasRenderedNodeChanges":true,"_timestamp":1732120601757398,"_typename":"NEvent.TNodeChanges","ymake_run_uid":1} 2024-11-20 16:36:41,759 DEBUG (build.ya_make) [Misc-02] Configure message {"_timestamp":1732120601758820,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":0} 2024-11-20 16:36:41,759 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":1732120601757412,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,759 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601758833,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,760 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":1732120601757419,"_typename":"NEvent.TUidsCacheStats","ymake_run_uid":1} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-02] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3066,"Sub":"","Type":"Debug","_timestamp":1732120601758844,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":0} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.01251103337,"Name":"EYmakeStats::WriteJSONTime","Type":"double","_timestamp":1732120601757495,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake main","_timestamp":1732120601759746,"_typename":"NEvent.TStageFinished","ymake_run_uid":0} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Write JSON","_timestamp":1732120601757500,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-02] Configure message {"DoubleValue":0.4576112708,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732120601759754,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":0} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"Export JSON","_timestamp":1732120601757523,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-02] Configure message {"StageName":"ymake run","_timestamp":1732120601759759,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-pic","ymake_run_uid":0} 2024-11-20 16:36:41,760 DEBUG (build.ya_make) [Misc-03] Configure message {"BoolValue":false,"Name":"EYmakeStats::UsedUidsCache","Type":"bool","_timestamp":1732120601757527,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,761 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":1732120601757534,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"InitModuleEnv":13,"InitModuleEnvCalls":13,"_timestamp":1732120601757539,"_typename":"NEvent.TMakeCommandStats","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"FS cache has been committed...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601760193,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Deps cache has been committed...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601760205,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"_timestamp":1732120601760211,"_typename":"NEvent.TReadyForUpdateCacheInfo","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"DM cache has been committed...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601760224,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"Message":"Uids cache has been committed...\n","Mod":"unimp","PID":3068,"Sub":"","Type":"Debug","_timestamp":1732120601760236,"_typename":"NEvent.TDisplayMessage","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake main","_timestamp":1732120601760865,"_typename":"NEvent.TStageFinished","ymake_run_uid":1} 2024-11-20 16:36:41,761 DEBUG (build.ya_make) [Misc-03] Configure message {"DoubleValue":0.4554076832,"Name":"EYmakeStats::RunTime","Type":"double","_timestamp":1732120601760872,"_typename":"NEvent.TMonitoringStat","ymake_run_uid":1} 2024-11-20 16:36:41,762 DEBUG (build.ya_make) [Misc-03] Configure message {"StageName":"ymake run","_timestamp":1732120601760879,"_typename":"NEvent.TStageFinished","debug_id":"default-linux-x86_64-release-asan-nopic","ymake_run_uid":1} 2024-11-20 16:36:41,765 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/1732120600.2963.pf7n3429/yatmpbyoxarcb --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/1732120600.2963.pf7n3429/gen_graph.30a8vghk/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.30a8vghk/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 16:36:41,765 DEBUG (build.ymake2) [Misc-02] ymake_run_info: {"ymake_run_uid":0,"stats":{"preparing":{"start":1732120601.29486346244812,"finish":1732120601.295411825180054,"duration":0.000548362731934},"execution":{"start":1732120601.295412540435791,"finish":1732120601.76528263092041,"duration":0.469870090484619},"postprocessing":{"start":1732120601.765285730361938,"finish":1732120601.765449285507202,"duration":0.000163555145264}},"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":1732120601.302124977111816,"finish":1732120601.759758949279785,"duration":0.457633972167969},"Load configuration from cache":{"start":1732120601.302274942398071,"finish":1732120601.302289009094238,"duration":0.000014066696167},"Load configuration (no cache)":{"start":1732120601.302568912506104,"finish":1732120601.469903945922852,"duration":0.167335033416748},"Compile and RecalcAll conditions":{"start":1732120601.469927072525024,"finish":1732120601.471606969833374,"duration":0.00167989730835},"Fold global commands":{"start":1732120601.471618890762329,"finish":1732120601.472913026809692,"duration":0.001294136047363},"Load plugins":{"start":1732120601.472922086715698,"finish":1732120601.598891973495483,"duration":0.125969886779785},"ymake main":{"start":1732120601.632690906524658,"finish":1732120601.759746074676514,"duration":0.127055168151855},"Build graph":{"start":1732120601.633764982223511,"finish":1732120601.734961986541748,"duration":0.101197004318237},"Configure graph":{"start":1732120601.633784055709839,"finish":1732120601.734637975692749,"duration":0.10085391998291},"Find Lost Includes":{"start":1732120601.734709024429321,"finish":1732120601.734792947769165,"duration":0.000083923339844},"Set reachable nodes":{"start":1732120601.735088109970093,"finish":1732120601.735696077346802,"duration":0.000607967376709},"Sort edges":{"start":1732120601.735697984695435,"finish":1732120601.738413095474243,"duration":0.002715110778809},"Report Foreign Platform Events":{"start":1732120601.738415956497192,"finish":1732120601.738466024398804,"duration":0.000050067901611},"Check blacklist":{"start":1732120601.738467931747436,"finish":1732120601.738471031188965,"duration":0.000003099441528},"Check isolated projects":{"start":1732120601.738472938537598,"finish":1732120601.738476037979126,"duration":0.000003099441528},"Report Configure Events":{"start":1732120601.738477945327759,"finish":1732120601.738534927368164,"duration":0.000056982040405},"Save and compact":{"start":1732120601.738537073135376,"finish":1732120601.740824937820435,"duration":0.002287864685059},"Detect loops":{"start":1732120601.740832090377808,"finish":1732120601.741319894790649,"duration":0.000487804412842},"Apply Dependency Management":{"start":1732120601.741322040557861,"finish":1732120601.741983890533447,"duration":0.000661849975586},"Save Dependency management cache":{"start":1732120601.741826057434082,"finish":1732120601.741981983184814,"duration":0.000155925750732},"Fill package outputs":{"start":1732120601.74202299118042,"finish":1732120601.742026090621948,"duration":0.000003099441528},"Check Transitive Requirements":{"start":1732120601.742029905319214,"finish":1732120601.742182970046997,"duration":0.000153064727783},"Find missing peerdirs":{"start":1732120601.742207050323486,"finish":1732120601.742925882339478,"duration":0.000718832015991},"Export JSON":{"start":1732120601.742985010147095,"finish":1732120601.755470037460327,"duration":0.012485027313232},"Write JSON":{"start":1732120601.742990016937256,"finish":1732120601.755446910858154,"duration":0.012456893920898},"Visit JSON":{"start":1732120601.742999076843262,"finish":1732120601.746320009231567,"duration":0.003320932388306},"Render JSON":{"start":1732120601.746325969696045,"finish":1732120601.754508018493652,"duration":0.008182048797607},"Save Uids cache":{"start":1732120601.754511117935181,"finish":1732120601.755319118499756,"duration":0.000808000564575}},"metrics":{"File access":{"loaded size":9020005,"load time":10727,"loaded MD5 time":805,"Max loaded MD5 time":5,"loaded count":2174,"max load time":200,"mapped size":40632376,"mapped MD5 time":7670,"Max mapped MD5 time":492,"mapped count":130,"map time":747,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3391,"lstat min us":1,"lstat avr us":1,"lstat max us":16,"opendir count":388,"opendir sum us":0,"opendir min us":0,"opendir avr us":0,"opendir max us":0,"readdir count":2837,"readdir sum us":2250,"readdir min us":0,"readdir avr us":0,"readdir max us":44,"(opendir + readdir) sum us":2250,"(lstat + opendir + readdir) sum us":5641},"Parsing":{"parse time":3,"parsed files count":6,"parsed files size":12965,"parsed files recovered":0,".in files count":0,".in files size":0},"ya.make parsing":{"count":8,"includes count":12,"unique count":8,"size":1264,"unique size":853},"DepGraph":{"nodes count":2825,"edges count":12363,"files count":3263,"commands count":142},"UpdIter":{"nuke mod dir":0},"Resolving":{"Includes attempts":0,"From cache":0,"Resolve AsKnown total":0,"Resolve AsKnown from cache":0},"TModules":{"accessed":19,"loaded":0,"outdated":0,"parsed":19,"total":19},"Internal cache":{"Total cache size on save":428512,"Diagnostics cache size on save":112,"Graph cache size on save":91616,"Parsers cache size on save":224,"Modules table size on save":3079,"Times table size on save":16,"Names table size on save":332624,"Commands cache size on save":272},"JSON cache":{"Loaded cache items":0,"Added cache items":0,"Saved old cache items":0,"Saved new cache items":0,"Saved total cache items":0,"Loaded full match items":0,"Full match requests":0,"Successful full matches":0,"Loaded partial match items":0,"Partial match requests":0,"Successful partial matches":0,"Fully rendered nodes":0,"Partially rendered nodes":0,"Nodes restored without rendering":0},"Uids cache":{"Loaded nodes":0,"Skipped nodes":0,"Discarded nodes":0,"Loaded loops":0,"Skipped loops":0,"Discarded loops":0,"Saved nodes":2392,"Saved loops":0,"Really all nodes no rendered":0},"TMakeCommand":{"module environment initialization calls":13,"actual module environment initializations":13}},"binary":"/home/runner/.ya/tools/v4/7448917907/ymake","args":["/home/runner/.ya/tools/v4/7448917907/ymake","--depends-like-recurse","--warn","dirloops,ChkPeers","--write-meta-data","/home/runner/.ya/tmp/1732120600.2963.pf7n3429/yatmpbyoxarcb","--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/1732120600.2963.pf7n3429/gen_graph.30a8vghk/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.30a8vghk/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.469870090484619} 2024-11-20 16:36:41,766 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=load-graph-from-json, group=graph, time=1732120601.7661524 2024-11-20 16:36:41,766 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_started=1732120601.7661524 2024-11-20 16:36:41,766 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_started - 1732120601.7661524 2024-11-20 16:36:41,766 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/1732120600.2963.pf7n3429/yatmpwpfhb1ed --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/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/makefiles.dart --dump-build-plan - --quiet --events dGT /home/runner/actions_runner/_work/ydb/ydb' finished 2024-11-20 16:36:41,766 DEBUG (core.event_handling.base_subscriber) [Misc-03] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 16:36:41,767 DEBUG (build.ymake2) [Misc-03] ymake_run_info: {"ymake_run_uid":1,"stats":{"preparing":{"start":1732120601.299019575119019,"finish":1732120601.299596548080444,"duration":0.000576972961426},"execution":{"start":1732120601.299597501754761,"finish":1732120601.766502380371094,"duration":0.466904878616333},"postprocessing":{"start":1732120601.766504049301148,"finish":1732120601.766762733459473,"duration":0.000258684158325}},"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":1732120601.305454015731812,"finish":1732120601.760879039764404,"duration":0.455425024032593},"Load configuration from cache":{"start":1732120601.305609941482544,"finish":1732120601.305624008178711,"duration":0.000014066696167},"Load configuration (no cache)":{"start":1732120601.305902004241943,"finish":1732120601.474800109863281,"duration":0.168898105621338},"Compile and RecalcAll conditions":{"start":1732120601.47481894493103,"finish":1732120601.476351976394653,"duration":0.001533031463623},"Fold global commands":{"start":1732120601.476365089416504,"finish":1732120601.477674007415772,"duration":0.001308917999268},"Load plugins":{"start":1732120601.477682113647461,"finish":1732120601.603755950927734,"duration":0.126073837280273},"ymake main":{"start":1732120601.637521028518677,"finish":1732120601.760864973068237,"duration":0.123343944549561},"Build graph":{"start":1732120601.638586044311524,"finish":1732120601.736912965774536,"duration":0.098326921463013},"Configure graph":{"start":1732120601.638605117797852,"finish":1732120601.736592054367065,"duration":0.097986936569214},"Find Lost Includes":{"start":1732120601.736654996871948,"finish":1732120601.736742973327637,"duration":0.000087976455688},"Set reachable nodes":{"start":1732120601.737061023712158,"finish":1732120601.73766303062439,"duration":0.000602006912231},"Sort edges":{"start":1732120601.737665891647339,"finish":1732120601.740467071533203,"duration":0.002801179885864},"Report Foreign Platform Events":{"start":1732120601.740470886230469,"finish":1732120601.740524053573608,"duration":0.00005316734314},"Check blacklist":{"start":1732120601.740525960922241,"finish":1732120601.740528106689453,"duration":0.000002145767212},"Check isolated projects":{"start":1732120601.740530967712402,"finish":1732120601.740533113479614,"duration":0.000002145767212},"Report Configure Events":{"start":1732120601.740535974502564,"finish":1732120601.740596055984497,"duration":0.000060081481934},"Save and compact":{"start":1732120601.74059796333313,"finish":1732120601.742713928222656,"duration":0.002115964889526},"Detect loops":{"start":1732120601.742719888687134,"finish":1732120601.743212938308716,"duration":0.000493049621582},"Apply Dependency Management":{"start":1732120601.743215084075928,"finish":1732120601.743983030319214,"duration":0.000767946243286},"Save Dependency management cache":{"start":1732120601.743818998336792,"finish":1732120601.743979930877686,"duration":0.000160932540894},"Fill package outputs":{"start":1732120601.744024991989136,"finish":1732120601.744026899337768,"duration":0.000001907348633},"Check Transitive Requirements":{"start":1732120601.744030952453613,"finish":1732120601.74418306350708,"duration":0.000152111053467},"Find missing peerdirs":{"start":1732120601.744204998016357,"finish":1732120601.744915008544922,"duration":0.000710010528564},"Export JSON":{"start":1732120601.744975090026856,"finish":1732120601.757523059844971,"duration":0.012547969818115},"Write JSON":{"start":1732120601.744981050491333,"finish":1732120601.757499933242798,"duration":0.012518882751465},"Visit JSON":{"start":1732120601.744990110397339,"finish":1732120601.748331069946289,"duration":0.00334095954895},"Render JSON":{"start":1732120601.748337030410767,"finish":1732120601.756489038467407,"duration":0.008152008056641},"Save Uids cache":{"start":1732120601.756491899490356,"finish":1732120601.757386922836304,"duration":0.000895023345947}},"metrics":{"File access":{"loaded size":9020005,"load time":9920,"loaded MD5 time":834,"Max loaded MD5 time":5,"loaded count":2174,"max load time":21,"mapped size":40632376,"mapped MD5 time":6661,"Max mapped MD5 time":421,"mapped count":130,"map time":717,"from patch count":0,"from patch size":0,"file stat count":3223,"lstat count":3045,"lstat sum us":3119,"lstat min us":0,"lstat avr us":1,"lstat max us":23,"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":2046,"readdir min us":0,"readdir avr us":0,"readdir max us":42,"(opendir + readdir) sum us":2046,"(lstat + opendir + readdir) sum us":5165},"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/1732120600.2963.pf7n3429/yatmpwpfhb1ed","--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/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.yfqnyb15/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.466904878616333} 2024-11-20 16:36:41,767 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=load-graph-from-json, group=graph, time=1732120601.7673237 2024-11-20 16:36:41,767 DEBUG (core.stages_profiler) [Misc-02] Set stage load-graph-from-json_finished=1732120601.7673237 2024-11-20 16:36:41,767 DEBUG (core.profiler) [Misc-02] Profile step load-graph-from-json_finished - 1732120601.7673237 2024-11-20 16:36:41,767 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=load-graph-from-json, group=graph, time=1732120601.7674847 2024-11-20 16:36:41,767 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_started=1732120601.7674847 2024-11-20 16:36:41,767 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_started - 1732120601.7674847 2024-11-20 16:36:41,767 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.767703 2024-11-20 16:36:41,767 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-json-default-linux-x86_64-release-asan-pic_finished=1732120601.767703 2024-11-20 16:36:41,768 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-json-default-linux-x86_64-release-asan-pic_finished - 1732120601.767703 2024-11-20 16:36:41,768 DEBUG (core.stage_tracer) [Misc-02] Start stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.7682323 2024-11-20 16:36:41,768 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_started=1732120601.7682323 2024-11-20 16:36:41,768 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_started - 1732120601.7682323 2024-11-20 16:36:41,772 DEBUG (build.graph) [Misc-02] Found 0 tests 2024-11-20 16:36:41,772 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=load-graph-from-json, group=graph, time=1732120601.7729547 2024-11-20 16:36:41,773 DEBUG (exts.timer) [Misc-02] Timer gen_tests, stage generate tests: 0.0003247261047363281 2024-11-20 16:36:41,773 DEBUG (core.stages_profiler) [Misc-03] Set stage load-graph-from-json_finished=1732120601.7729547 2024-11-20 16:36:41,773 DEBUG (core.profiler) [Misc-03] Profile step load-graph-from-json_finished - 1732120601.7729547 2024-11-20 16:36:41,773 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-json-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.7734187 2024-11-20 16:36:41,773 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-json-default-linux-x86_64-release-asan-nopic_finished=1732120601.7734187 2024-11-20 16:36:41,773 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-json-default-linux-x86_64-release-asan-nopic_finished - 1732120601.7734187 2024-11-20 16:36:41,773 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.773596 2024-11-20 16:36:41,773 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-tests-default-linux-x86_64-release-asan-pic_finished=1732120601.773596 2024-11-20 16:36:41,773 DEBUG (core.profiler) [Misc-02] Profile step gen-tests-default-linux-x86_64-release-asan-pic_finished - 1732120601.773596 2024-11-20 16:36:41,773 DEBUG (core.stage_tracer) [Misc-03] Start stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.7737129 2024-11-20 16:36:41,774 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_started=1732120601.7737129 2024-11-20 16:36:41,774 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_started - 1732120601.7737129 2024-11-20 16:36:41,774 DEBUG (core.stage_tracer) [Misc-02] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-pic, group=graph, time=1732120601.7741673 2024-11-20 16:36:41,774 DEBUG (core.stages_profiler) [Misc-02] Set stage gen-graph-default-linux-x86_64-release-asan-pic_finished=1732120601.7741673 2024-11-20 16:36:41,774 DEBUG (core.profiler) [Misc-02] Profile step gen-graph-default-linux-x86_64-release-asan-pic_finished - 1732120601.7741673 2024-11-20 16:36:41,774 DEBUG (build.graph) [Misc-03] Found 0 tests 2024-11-20 16:36:41,774 DEBUG (exts.timer) [Misc-03] Timer gen_tests, stage generate tests: 0.00019359588623046875 2024-11-20 16:36:41,774 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-tests-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.7748644 2024-11-20 16:36:41,774 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-tests-default-linux-x86_64-release-asan-nopic_finished=1732120601.7748644 2024-11-20 16:36:41,774 DEBUG (core.profiler) [Misc-03] Profile step gen-tests-default-linux-x86_64-release-asan-nopic_finished - 1732120601.7748644 2024-11-20 16:36:41,775 DEBUG (core.stage_tracer) [Misc-03] Finish stage tag=gen-graph-default-linux-x86_64-release-asan-nopic, group=graph, time=1732120601.7751963 2024-11-20 16:36:41,775 DEBUG (core.stages_profiler) [Misc-03] Set stage gen-graph-default-linux-x86_64-release-asan-nopic_finished=1732120601.7751963 2024-11-20 16:36:41,775 DEBUG (core.profiler) [Misc-03] Profile step gen-graph-default-linux-x86_64-release-asan-nopic_finished - 1732120601.7751963 2024-11-20 16:36:41,775 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage waiting_graphs for default-linux-x86_64-release-asan: 0.028558731079101562 2024-11-20 16:36:41,775 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=wait-target-graphs-1, group=graph, time=1732120601.775598 2024-11-20 16:36:41,775 DEBUG (core.stages_profiler) [MainThread] Set stage wait-target-graphs-1_finished=1732120601.775598 2024-11-20 16:36:41,775 DEBUG (core.profiler) [MainThread] Profile step wait-target-graphs-1_finished - 1732120601.775598 2024-11-20 16:36:41,775 DEBUG (core.stage_tracer) [MainThread] Start stage tag=union-make-files-1, group=graph, time=1732120601.7758589 2024-11-20 16:36:41,775 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_started=1732120601.7758589 2024-11-20 16:36:41,775 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_started - 1732120601.7758589 2024-11-20 16:36:41,776 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage union make files for default-linux-x86_64-release-asan: 0.0005393028259277344 2024-11-20 16:36:41,776 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=union-make-files-1, group=graph, time=1732120601.7760546 2024-11-20 16:36:41,776 DEBUG (core.stages_profiler) [MainThread] Set stage union-make-files-1_finished=1732120601.7760546 2024-11-20 16:36:41,776 DEBUG (core.profiler) [MainThread] Profile step union-make-files-1_finished - 1732120601.7760546 2024-11-20 16:36:41,776 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-graphs-1, group=graph, time=1732120601.7761993 2024-11-20 16:36:41,776 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_started=1732120601.7761993 2024-11-20 16:36:41,776 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_started - 1732120601.7761993 2024-11-20 16:36:41,776 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage merge_graphs for default-linux-x86_64-release-asan: 0.00035643577575683594 2024-11-20 16:36:41,776 DEBUG (build.graph) [MainThread] Graph size is 26 after merge_graphs 2024-11-20 16:36:41,776 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-graphs-1, group=graph, time=1732120601.7764587 2024-11-20 16:36:41,776 DEBUG (core.stages_profiler) [MainThread] Set stage merge-graphs-1_finished=1732120601.7764587 2024-11-20 16:36:41,776 DEBUG (core.profiler) [MainThread] Profile step merge-graphs-1_finished - 1732120601.7764587 2024-11-20 16:36:41,776 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph-1, group=graph, time=1732120601.7766213 2024-11-20 16:36:41,776 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_started=1732120601.7766213 2024-11-20 16:36:41,776 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_started - 1732120601.7766213 2024-11-20 16:36:41,776 DEBUG (exts.timer) [MainThread] Timer merge_target_graphs, stage strip_graph for default-linux-x86_64-release-asan: 0.0003948211669921875 2024-11-20 16:36:41,776 DEBUG (build.graph) [MainThread] Graph size is 13 after strip_graph 2024-11-20 16:36:41,776 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph-1, group=graph, time=1732120601.776845 2024-11-20 16:36:41,776 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph-1_finished=1732120601.776845 2024-11-20 16:36:41,776 DEBUG (core.profiler) [MainThread] Profile step strip-graph-1_finished - 1732120601.776845 2024-11-20 16:36:41,777 DEBUG (build.graph) [MainThread] Configuring only non-PIC for test_tool_tc1-global 2024-11-20 16:36:41,777 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732120601.7774367 2024-11-20 16:36:41,777 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_started=1732120601.7774367 2024-11-20 16:36:41,777 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_started - 1732120601.7774367 2024-11-20 16:36:41,777 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732120601.7778606 2024-11-20 16:36:41,777 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_started=1732120601.7778606 2024-11-20 16:36:41,777 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_started - 1732120601.7778606 2024-11-20 16:36:41,778 DEBUG (yalibrary.guards) [Misc-04] Enter guard fetch with value None 2024-11-20 16:36:41,793 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 16:36:41,925 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 16:36:41,926 DEBUG (yalibrary.guards) [Misc-04] Exit guard fetch with value None 2024-11-20 16:36:41,926 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-gen-opts-test_tool_tc1-nopic-global, group=graph, time=1732120601.9261327 2024-11-20 16:36:41,926 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-gen-opts-test_tool_tc1-nopic-global_finished=1732120601.9261327 2024-11-20 16:36:41,926 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-gen-opts-test_tool_tc1-nopic-global_finished - 1732120601.9261327 2024-11-20 16:36:41,926 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732120601.926345 2024-11-20 16:36:41,926 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_started=1732120601.926345 2024-11-20 16:36:41,926 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_started - 1732120601.926345 2024-11-20 16:36:41,926 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 0x7f1493ca3880>, 'enabled_events': 'PSLGE', 'no_caches_on_retry': False, 'no_ymake_retry': False, 'disable_customization': 0, 'dump_tests': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.9avxmo91/test.dart', 'dump_make_files': '/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.9avxmo91/makefiles.dart'} 2024-11-20 16:36:41,926 DEBUG (core.yarg.behaviour) [Misc-04] Found unused args ['grab_stderr'] 2024-11-20 16:36:41,926 DEBUG (build.prefetch) [Misc-04] prefetch disabled 2024-11-20 16:36:41,926 DEBUG (core.event_handling.base_subscriber) [Misc-04] Subscribing PrintProgressSubscriber to event_queue 2024-11-20 16:36:41,927 DEBUG (yalibrary.tools) [Misc-04] Using old-style toolchain for: ymake 2024-11-20 16:36:41,927 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/1732120600.2963.pf7n3429/yatmp110i0qu2 --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/1732120600.2963.pf7n3429/gen_graph.9avxmo91/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.9avxmo91/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 16:36:42,308 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/1732120600.2963.pf7n3429/yatmp110i0qu2 --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/1732120600.2963.pf7n3429/gen_graph.9avxmo91/test.dart --tests-data-root /home/runner/actions_runner/_work/ydb/arcadia_tests_data --makefiles-dart /home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.9avxmo91/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 16:36:42,309 DEBUG (core.event_handling.base_subscriber) [Misc-04] Unsubscribing PrintProgressSubscriber from event_queue 2024-11-20 16:36:42,309 DEBUG (build.ymake2) [Misc-04] ymake_run_info: {"ymake_run_uid":2,"stats":{"preparing":{"start":1732120601.926770687103272,"finish":1732120601.927388429641724,"duration":0.000617742538452},"execution":{"start":1732120601.927388906478882,"finish":1732120602.309031009674072,"duration":0.38164210319519},"postprocessing":{"start":1732120602.309033632278442,"finish":1732120602.309391260147095,"duration":0.000357627868652}},"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/1732120600.2963.pf7n3429/yatmp110i0qu2","--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/1732120600.2963.pf7n3429/gen_graph.9avxmo91/test.dart","--tests-data-root","/home/runner/actions_runner/_work/ydb/arcadia_tests_data","--makefiles-dart","/home/runner/.ya/tmp/1732120600.2963.pf7n3429/gen_graph.9avxmo91/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.38164210319519} 2024-11-20 16:36:42,309 DEBUG (core.stage_tracer) [Misc-04] Start stage tag=load-graph-from-json, group=graph, time=1732120602.3098469 2024-11-20 16:36:42,309 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_started=1732120602.3098469 2024-11-20 16:36:42,309 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_started - 1732120602.3098469 2024-11-20 16:36:42,310 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=load-graph-from-json, group=graph, time=1732120602.3101141 2024-11-20 16:36:42,310 DEBUG (core.stages_profiler) [Misc-04] Set stage load-graph-from-json_finished=1732120602.3101141 2024-11-20 16:36:42,310 DEBUG (core.profiler) [Misc-04] Profile step load-graph-from-json_finished - 1732120602.3101141 2024-11-20 16:36:42,310 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-json-test_tool_tc1-nopic-global, group=graph, time=1732120602.3102887 2024-11-20 16:36:42,310 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-json-test_tool_tc1-nopic-global_finished=1732120602.3102887 2024-11-20 16:36:42,310 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-json-test_tool_tc1-nopic-global_finished - 1732120602.3102887 2024-11-20 16:36:42,310 DEBUG (core.stage_tracer) [Misc-04] Finish stage tag=gen-graph-test_tool_tc1-nopic-global, group=graph, time=1732120602.3105586 2024-11-20 16:36:42,310 DEBUG (core.stages_profiler) [Misc-04] Set stage gen-graph-test_tool_tc1-nopic-global_finished=1732120602.3105586 2024-11-20 16:36:42,310 DEBUG (core.profiler) [Misc-04] Profile step gen-graph-test_tool_tc1-nopic-global_finished - 1732120602.3105586 2024-11-20 16:36:42,311 DEBUG (core.stage_tracer) [MainThread] Start stage tag=get-graph-1, group=graph, time=1732120602.3111696 2024-11-20 16:36:42,311 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_started=1732120602.3111696 2024-11-20 16:36:42,311 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_started - 1732120602.3111696 2024-11-20 16:36:42,313 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=get-graph-1, group=graph, time=1732120602.3130562 2024-11-20 16:36:42,313 DEBUG (core.stages_profiler) [MainThread] Set stage get-graph-1_finished=1732120602.3130562 2024-11-20 16:36:42,313 DEBUG (core.profiler) [MainThread] Profile step get-graph-1_finished - 1732120602.3130562 2024-11-20 16:36:42,313 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732120602.3133013 2024-11-20 16:36:42,313 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732120602.3133013 2024-11-20 16:36:42,313 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732120602.3133013 2024-11-20 16:36:42,313 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build-merged-graph, group=graph, time=1732120602.3134706 2024-11-20 16:36:42,313 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_started=1732120602.3134706 2024-11-20 16:36:42,313 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_started - 1732120602.3134706 2024-11-20 16:36:42,314 DEBUG (core.stage_tracer) [MainThread] Start stage tag=insert-tests-1, group=graph, time=1732120602.314627 2024-11-20 16:36:42,314 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_started=1732120602.314627 2024-11-20 16:36:42,314 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_started - 1732120602.314627 2024-11-20 16:36:42,314 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'unsupported cross-compiled tests' on 0 suites 2024-11-20 16:36:42,314 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter '. at 0x7f1493d260c0>' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'size' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'class type' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'suite type' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Tags filter include set:[] exclude set:[] 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'tags' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'project path' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'filename filter' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'chunk filter' on 0 suites 2024-11-20 16:36:42,315 DEBUG (devtools.ya.test.filter) [MainThread] Going to apply filter 'name' on 0 suites 2024-11-20 16:36:42,315 DEBUG (build.graph) [MainThread] Generating build plan 2024-11-20 16:36:42,315 DEBUG (build.graph) [MainThread] Preparing test suites 2024-11-20 16:36:42,315 DEBUG (build.graph) [MainThread] Stripping clang-tidy irrelevant deps 2024-11-20 16:36:42,315 DEBUG (exts.timer) [MainThread] Timer inject_tests, stage inject tests for default-linux-x86_64-release-asan: 0.0011200904846191406 2024-11-20 16:36:42,315 DEBUG (build.graph) [MainThread] injected 0 tests for default-linux-x86_64-release-asan 2024-11-20 16:36:42,316 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=insert-tests-1, group=graph, time=1732120602.3160417 2024-11-20 16:36:42,316 DEBUG (core.stages_profiler) [MainThread] Set stage insert-tests-1_finished=1732120602.3160417 2024-11-20 16:36:42,316 DEBUG (core.profiler) [MainThread] Profile step insert-tests-1_finished - 1732120602.3160417 2024-11-20 16:36:42,316 DEBUG (core.stage_tracer) [MainThread] Start stage tag=merge-target-graphs-1, group=graph, time=1732120602.316205 2024-11-20 16:36:42,316 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_started=1732120602.316205 2024-11-20 16:36:42,316 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_started - 1732120602.316205 2024-11-20 16:36:42,316 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=merge-target-graphs-1, group=graph, time=1732120602.3163428 2024-11-20 16:36:42,316 DEBUG (core.stages_profiler) [MainThread] Set stage merge-target-graphs-1_finished=1732120602.3163428 2024-11-20 16:36:42,316 DEBUG (core.profiler) [MainThread] Profile step merge-target-graphs-1_finished - 1732120602.3163428 2024-11-20 16:36:42,316 DEBUG (core.config) [MainThread] Read config "build/platform/python/ymake_python2/resources.json" from resource 2024-11-20 16:36:42,316 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 16:36:42,317 DEBUG (core.config) [MainThread] Reading contents of /home/runner/actions_runner/_work/ydb/ydb/build/external_resources/gdb/resources.json 2024-11-20 16:36:42,317 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] gdb: will use 'linux-x86_64' platform 2024-11-20 16:36:42,317 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build-merged-graph, group=graph, time=1732120602.3173132 2024-11-20 16:36:42,317 DEBUG (core.stages_profiler) [MainThread] Set stage build-merged-graph_finished=1732120602.3173132 2024-11-20 16:36:42,317 DEBUG (core.profiler) [MainThread] Profile step build-merged-graph_finished - 1732120602.3173132 2024-11-20 16:36:42,317 DEBUG (core.stage_tracer) [MainThread] Start stage tag=iter-extra-resources, group=graph, time=1732120602.3174858 2024-11-20 16:36:42,317 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_started=1732120602.3174858 2024-11-20 16:36:42,317 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_started - 1732120602.3174858 2024-11-20 16:36:42,317 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=iter-extra-resources, group=graph, time=1732120602.3176515 2024-11-20 16:36:42,317 DEBUG (core.stages_profiler) [MainThread] Set stage iter-extra-resources_finished=1732120602.3176515 2024-11-20 16:36:42,317 DEBUG (core.profiler) [MainThread] Profile step iter-extra-resources_finished - 1732120602.3176515 2024-11-20 16:36:42,317 DEBUG (exts.timer) [MainThread] Timer build.graph, stage build and merge graphs: 1.2039151191711426 2024-11-20 16:36:42,317 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-graph, group=graph, time=1732120602.3178523 2024-11-20 16:36:42,317 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_started=1732120602.3178523 2024-11-20 16:36:42,317 DEBUG (core.profiler) [MainThread] Profile step strip-graph_started - 1732120602.3178523 2024-11-20 16:36:42,318 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 16:36:42,318 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip graph: 0.0002522468566894531 2024-11-20 16:36:42,318 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-graph, group=graph, time=1732120602.3180974 2024-11-20 16:36:42,318 DEBUG (core.stages_profiler) [MainThread] Set stage strip-graph_finished=1732120602.3180974 2024-11-20 16:36:42,318 DEBUG (core.profiler) [MainThread] Profile step strip-graph_finished - 1732120602.3180974 2024-11-20 16:36:42,318 DEBUG (exts.timer) [MainThread] Timer build.graph, stage gen rename nodes: 0.00021147727966308594 2024-11-20 16:36:42,318 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] python: will use 'linux-x86_64' platform 2024-11-20 16:36:42,318 DEBUG (yalibrary.fetcher.tool_chain_fetcher) [MainThread] ymake: will use 'linux' platform 2024-11-20 16:36:42,318 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_default_requirements, group=graph, time=1732120602.3185663 2024-11-20 16:36:42,318 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_started=1732120602.3185663 2024-11-20 16:36:42,318 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_started - 1732120602.3185663 2024-11-20 16:36:42,318 DEBUG (build.graph) [MainThread] Updated requirements for 0 nodes 2024-11-20 16:36:42,318 DEBUG (exts.timer) [MainThread] Timer substitude_uids, stage substitude_uids: 9.059906005859375e-06 2024-11-20 16:36:42,318 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_default_requirements, group=graph, time=1732120602.3188152 2024-11-20 16:36:42,318 DEBUG (core.stages_profiler) [MainThread] Set stage inject_default_requirements_finished=1732120602.3188152 2024-11-20 16:36:42,318 DEBUG (core.profiler) [MainThread] Profile step inject_default_requirements_finished - 1732120602.3188152 2024-11-20 16:36:42,318 DEBUG (exts.timer) [MainThread] Timer build.graph, stage misc graph changes: 0.0006966590881347656 2024-11-20 16:36:42,319 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-graph, group=graph, time=1732120602.3190093 2024-11-20 16:36:42,319 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_started=1732120602.3190093 2024-11-20 16:36:42,319 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_started - 1732120602.3190093 2024-11-20 16:36:42,319 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-graph, group=graph, time=1732120602.3191385 2024-11-20 16:36:42,319 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-graph_finished=1732120602.3191385 2024-11-20 16:36:42,319 DEBUG (core.profiler) [MainThread] Profile step finalize-graph_finished - 1732120602.3191385 2024-11-20 16:36:42,319 DEBUG (core.stage_tracer) [MainThread] Start stage tag=imprint-store-and-stats, group=graph, time=1732120602.3192768 2024-11-20 16:36:42,319 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_started=1732120602.3192768 2024-11-20 16:36:42,319 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_started - 1732120602.3192768 2024-11-20 16:36:42,319 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 16:36:42,319 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 16:36:42,319 DEBUG (core.imprint.imprint:Imprint) [MainThread] 2024-11-20 16:36:42,319 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=imprint-store-and-stats, group=graph, time=1732120602.3195975 2024-11-20 16:36:42,319 DEBUG (core.stages_profiler) [MainThread] Set stage imprint-store-and-stats_finished=1732120602.3195975 2024-11-20 16:36:42,319 DEBUG (core.profiler) [MainThread] Profile step imprint-store-and-stats_finished - 1732120602.3195975 2024-11-20 16:36:42,319 DEBUG (core.stage_tracer) [MainThread] Start stage tag=inject_stats_and_static_uids, group=graph, time=1732120602.3197432 2024-11-20 16:36:42,319 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_started=1732120602.3197432 2024-11-20 16:36:42,319 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_started - 1732120602.3197432 2024-11-20 16:36:42,320 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=inject_stats_and_static_uids, group=graph, time=1732120602.3200517 2024-11-20 16:36:42,320 DEBUG (core.stages_profiler) [MainThread] Set stage inject_stats_and_static_uids_finished=1732120602.3200517 2024-11-20 16:36:42,320 DEBUG (core.profiler) [MainThread] Profile step inject_stats_and_static_uids_finished - 1732120602.3200517 2024-11-20 16:36:42,320 DEBUG (core.stage_tracer) [MainThread] Start stage tag=strip-tags, group=graph, time=1732120602.3202178 2024-11-20 16:36:42,320 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_started=1732120602.3202178 2024-11-20 16:36:42,320 DEBUG (core.profiler) [MainThread] Profile step strip-tags_started - 1732120602.3202178 2024-11-20 16:36:42,320 DEBUG (exts.timer) [MainThread] Timer build.graph, stage strip tags: 0.0014126300811767578 2024-11-20 16:36:42,320 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=strip-tags, group=graph, time=1732120602.3204224 2024-11-20 16:36:42,320 DEBUG (core.stages_profiler) [MainThread] Set stage strip-tags_finished=1732120602.3204224 2024-11-20 16:36:42,320 DEBUG (core.profiler) [MainThread] Profile step strip-tags_finished - 1732120602.3204224 2024-11-20 16:36:42,320 DEBUG (core.stage_tracer) [MainThread] Start stage tag=reduce_graph_resources, group=graph, time=1732120602.3206573 2024-11-20 16:36:42,320 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_started=1732120602.3206573 2024-11-20 16:36:42,320 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_started - 1732120602.3206573 2024-11-20 16:36:42,320 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=reduce_graph_resources, group=graph, time=1732120602.3209734 2024-11-20 16:36:42,321 DEBUG (core.stages_profiler) [MainThread] Set stage reduce_graph_resources_finished=1732120602.3209734 2024-11-20 16:36:42,321 DEBUG (core.profiler) [MainThread] Profile step reduce_graph_resources_finished - 1732120602.3209734 2024-11-20 16:36:42,321 DEBUG (core.stage_tracer) [MainThread] Start stage tag=clean-intern-string-storage, group=graph, time=1732120602.3211331 2024-11-20 16:36:42,321 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_started=1732120602.3211331 2024-11-20 16:36:42,321 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_started - 1732120602.3211331 2024-11-20 16:36:42,321 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=clean-intern-string-storage, group=graph, time=1732120602.3213382 2024-11-20 16:36:42,321 DEBUG (core.stages_profiler) [MainThread] Set stage clean-intern-string-storage_finished=1732120602.3213382 2024-11-20 16:36:42,321 DEBUG (core.profiler) [MainThread] Profile step clean-intern-string-storage_finished - 1732120602.3213382 2024-11-20 16:36:42,321 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build_graph_and_tests, group=graph, time=1732120602.3214786 2024-11-20 16:36:42,321 DEBUG (core.stages_profiler) [MainThread] Set stage build_graph_and_tests_finished=1732120602.3214786 2024-11-20 16:36:42,321 DEBUG (core.profiler) [MainThread] Profile step build_graph_and_tests_finished - 1732120602.3214786 2024-11-20 16:36:42,321 DEBUG (exts.timer) [MainThread] Timer context_creation, stage graph_and_tests finished: 1.2428722381591797 2024-11-20 16:36:42,323 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-dist-store-cache, group=ya_make, time=1732120602.3232505 2024-11-20 16:36:42,323 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_started=1732120602.3232505 2024-11-20 16:36:42,323 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_started - 1732120602.3232505 2024-11-20 16:36:42,323 DEBUG (build.ya_make) [MainThread] Waiting for dist cache setup 2024-11-20 16:36:42,323 DEBUG (build.ya_make) [MainThread] Loading meta from dist cache 2024-11-20 16:36:42,323 DEBUG (build.ya_make) [MainThread] Dist cache prepared 2024-11-20 16:36:42,323 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-dist-store-cache, group=ya_make, time=1732120602.3235822 2024-11-20 16:36:42,323 DEBUG (core.stages_profiler) [MainThread] Set stage configure-dist-store-cache_finished=1732120602.3235822 2024-11-20 16:36:42,323 DEBUG (core.profiler) [MainThread] Profile step configure-dist-store-cache_finished - 1732120602.3235822 2024-11-20 16:36:42,323 DEBUG (core.stage_tracer) [MainThread] Start stage tag=configure-local-cache, group=ya_make, time=1732120602.3237376 2024-11-20 16:36:42,323 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_started=1732120602.3237376 2024-11-20 16:36:42,323 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_started - 1732120602.3237376 2024-11-20 16:36:42,323 DEBUG (build.ya_make) [MainThread] content UIDs forced 2024-11-20 16:36:42,323 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=configure-local-cache, group=ya_make, time=1732120602.3239677 2024-11-20 16:36:42,324 DEBUG (core.stages_profiler) [MainThread] Set stage configure-local-cache_finished=1732120602.3239677 2024-11-20 16:36:42,324 DEBUG (core.profiler) [MainThread] Profile step configure-local-cache_finished - 1732120602.3239677 2024-11-20 16:36:42,324 DEBUG (build.ya_make) [MainThread] sandbox_run_test_uids: set() 2024-11-20 16:36:42,326 DEBUG (urllib3.connectionpool) [Misc-05] Starting new HTTP connection (1): cachesrv.internal:8081 2024-11-20 16:36:42,327 DEBUG (urllib3.connectionpool) [Misc-06] Starting new HTTP connection (2): cachesrv.internal:8081 2024-11-20 16:36:42,328 DEBUG (urllib3.connectionpool) [Misc-07] Starting new HTTP connection (3): cachesrv.internal:8081 2024-11-20 16:36:42,329 DEBUG (urllib3.connectionpool) [Misc-08] Starting new HTTP connection (4): cachesrv.internal:8081 2024-11-20 16:36:42,330 DEBUG (urllib3.connectionpool) [Misc-09] Starting new HTTP connection (5): cachesrv.internal:8081 2024-11-20 16:36:42,331 DEBUG (urllib3.connectionpool) [Misc-10] Starting new HTTP connection (6): cachesrv.internal:8081 2024-11-20 16:36:42,333 DEBUG (urllib3.connectionpool) [Misc-11] Starting new HTTP connection (7): cachesrv.internal:8081 2024-11-20 16:36:42,334 DEBUG (urllib3.connectionpool) [Misc-12] Starting new HTTP connection (8): cachesrv.internal:8081 2024-11-20 16:36:42,335 DEBUG (urllib3.connectionpool) [Misc-13] Starting new HTTP connection (9): cachesrv.internal:8081 2024-11-20 16:36:42,336 DEBUG (urllib3.connectionpool) [Misc-14] Starting new HTTP connection (10): cachesrv.internal:8081 2024-11-20 16:36:42,337 DEBUG (urllib3.connectionpool) [Misc-15] Starting new HTTP connection (11): cachesrv.internal:8081 2024-11-20 16:36:42,338 DEBUG (urllib3.connectionpool) [Misc-16] Starting new HTTP connection (12): cachesrv.internal:8081 2024-11-20 16:36:42,339 DEBUG (urllib3.connectionpool) [Misc-17] Starting new HTTP connection (13): cachesrv.internal:8081 2024-11-20 16:36:42,345 DEBUG (urllib3.connectionpool) [Misc-06] http://cachesrv.internal:8081 "HEAD /ac/68384d6d49524269427070785357365475666235595100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,346 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-06] Bazel-remote Probing h8MmIRBiBppxSW6Tufb5YQ => False 2024-11-20 16:36:42,347 DEBUG (urllib3.connectionpool) [Misc-07] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,347 DEBUG (urllib3.connectionpool) [Misc-05] http://cachesrv.internal:8081 "HEAD /ac/2d7054776e4e484c37653736515a4c697071546a367700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,347 DEBUG (urllib3.connectionpool) [Misc-08] http://cachesrv.internal:8081 "HEAD /ac/48445f4470507531367a33734778683249317966696700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,347 DEBUG (urllib3.connectionpool) [Misc-10] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,348 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-07] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 16:36:42,348 DEBUG (urllib3.connectionpool) [Misc-09] http://cachesrv.internal:8081 "HEAD /ac/63323767355f484e662d736a48375539504f2d68564100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,349 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-05] Bazel-remote Probing -pTwnNHL7e76QZLipqTj6w => False 2024-11-20 16:36:42,349 DEBUG (urllib3.connectionpool) [Misc-11] http://cachesrv.internal:8081 "HEAD /ac/4e54347458416470685f63324c5f4145576f49476c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,349 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-08] Bazel-remote Probing HD_DpPu16z3sGxh2I1yfig => False 2024-11-20 16:36:42,349 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-10] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 16:36:42,350 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-09] Bazel-remote Probing c27g5_HNf-sjH7U9PO-hVA => False 2024-11-20 16:36:42,350 DEBUG (urllib3.connectionpool) [Misc-12] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,350 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-11] Bazel-remote Probing NT4tXAdph_c2L_AEWoIGlQ => False 2024-11-20 16:36:42,351 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-12] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 16:36:42,354 DEBUG (urllib3.connectionpool) [Misc-13] http://cachesrv.internal:8081 "HEAD /ac/546d653532436235454d345f77724b70736e38464c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,354 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-13] Bazel-remote Probing Tme52Cb5EM4_wrKpsn8FLQ => False 2024-11-20 16:36:42,355 DEBUG (urllib3.connectionpool) [Misc-15] http://cachesrv.internal:8081 "HEAD /ac/753939734c6869385266476b79365858586a776d4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,355 DEBUG (urllib3.connectionpool) [Misc-16] http://cachesrv.internal:8081 "HEAD /ac/7530774b3034394f794755317a74676d487638566a5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,355 DEBUG (urllib3.connectionpool) [Misc-17] http://cachesrv.internal:8081 "HEAD /ac/7345326e416c376c5f5a57583738653571324b545a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,356 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-15] Bazel-remote Probing u99sLhi8RfGky6XXXjwmMQ => False 2024-11-20 16:36:42,356 DEBUG (urllib3.connectionpool) [Misc-14] http://cachesrv.internal:8081 "HEAD /ac/5f635a54705579642d544f797841536a69383439437700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,356 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-16] Bazel-remote Probing u0wK049OyGU1ztgmHv8VjQ => False 2024-11-20 16:36:42,356 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-17] Bazel-remote Probing sE2nAl7l_ZWX78e5q2KTZw => False 2024-11-20 16:36:42,357 DEBUG (yalibrary.store.bazel_store.bazel_store) [Misc-14] Bazel-remote Probing _cZTpUyd-TOyxASji849Cw => False 2024-11-20 16:36:42,357 DEBUG (build.ya_make) [MainThread] Strip graph due bazel_remote_store mode 2024-11-20 16:36:42,357 DEBUG (build.graph) [MainThread] stripped 0, left 13 nodes 2024-11-20 16:36:42,362 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_creation, group=ya_make, time=1732120602.3623464 2024-11-20 16:36:42,362 DEBUG (core.stages_profiler) [MainThread] Set stage context_creation_finished=1732120602.3623464 2024-11-20 16:36:42,362 DEBUG (core.profiler) [MainThread] Profile step context_creation_finished - 1732120602.3623464 2024-11-20 16:36:42,362 DEBUG (exts.timer) [MainThread] Timer context_creation, stage context_creation finished: 0.04076051712036133 2024-11-20 16:36:42,362 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=context_generating, group=build_handler, time=1732120602.3628426 2024-11-20 16:36:42,362 DEBUG (core.stages_profiler) [MainThread] Set stage context_generating_finished=1732120602.3628426 2024-11-20 16:36:42,362 DEBUG (core.profiler) [MainThread] Profile step context_generating_finished - 1732120602.3628426 2024-11-20 16:36:42,363 DEBUG (core.stage_tracer) [MainThread] Start stage tag=build, group=build_handler, time=1732120602.363044 2024-11-20 16:36:42,363 DEBUG (core.stages_profiler) [MainThread] Set stage build_started=1732120602.363044 2024-11-20 16:36:42,363 DEBUG (core.profiler) [MainThread] Profile step build_started - 1732120602.363044 2024-11-20 16:36:42,363 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 16:36:42,363 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 16:36:42,363 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 16:36:42,363 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 16:36:42,363 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 16:36:42,363 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 16:36:42,363 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 16:36:42,364 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 16:36:42,364 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 16:36:42,364 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 16:36:42,364 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 16:36:42,364 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 16:36:42,365 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 16:36:42,365 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 16:36:42,365 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 16:36:42,365 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 16:36:42,365 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 16:36:42,365 DEBUG (ReportGenerator) [MainThread] Finish configure report 2024-11-20 16:36:42,365 DEBUG (StoredReport) [MainThread] Finish configure report 2024-11-20 16:36:42,365 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dispatch_build, group=ya_make, time=1732120602.3653002 2024-11-20 16:36:42,365 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_started=1732120602.3653002 2024-11-20 16:36:42,365 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_started - 1732120602.3653002 2024-11-20 16:36:42,365 DEBUG (yalibrary.runner.runner3) [MainThread] RLIMIT_NOFILE limits: (524288, 524288) 2024-11-20 16:36:42,377 DEBUG (root) [MainThread] Starting local executor with cmd: ['/home/runner/.ya/tools/38e015ab6e_d/ya-bin', 'unix:/home/runner/.ya/tmp/1732120600.2963.pf7n3429/2963gz45e9b3', '0'] 2024-11-20 16:36:42,378 DEBUG (root) [MainThread] Not waiting for initialization, returning waiter function 2024-11-20 16:36:42,403 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/build_root/thpb/STAMP 2024-11-20 16:36:42,403 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage resolve: 3.886222839355469e-05 2024-11-20 16:36:42,403 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build nodes: 0.00016498565673828125 2024-11-20 16:36:42,403 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build who provides, ref count: 6.628036499023438e-05 2024-11-20 16:36:42,403 DEBUG (exts.timer) [MainThread] Timer yalibrary.runner.runner3, stage build ref count: 6.341934204101562e-05 2024-11-20 16:36:42,404 DEBUG (yalibrary.runner.runner3) [MainThread] content UIDs *enabled* in runner 2024-11-20 16:36:42,561 DEBUG (yalibrary.runner.runner3) [Worker-002] Local executor is ready 2024-11-20 16:36:42,566 DEBUG (yalibrary.toolscache) [PrepareAllNodes_0] AC cache (has uid HD_DpPu16z3sGxh2I1yfig) address: (2537, 25446, 'unix:/home/runner/.ya/build/cache/7/psingtMYyeR.tmp') 2024-11-20 16:36:42,576 DEBUG (urllib3.connectionpool) [Worker-004] http://cachesrv.internal:8081 "HEAD /ac/48445f4470507531367a33734778683249317966696700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,578 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for -pTwnNHL7e76QZLipqTj6w created (provider=None) 2024-11-20 16:36:42,578 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-004] Bazel-remote Probing HD_DpPu16z3sGxh2I1yfig => False 2024-11-20 16:36:42,578 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/63323767355f484e662d736a48375539504f2d68564100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,579 DEBUG (urllib3.connectionpool) [Worker-009] http://cachesrv.internal:8081 "HEAD /ac/68384d6d49524269427070785357365475666235595100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,580 DEBUG (urllib3.connectionpool) [Worker-010] http://cachesrv.internal:8081 "HEAD /ac/546d653532436235454d345f77724b70736e38464c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,581 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for h8MmIRBiBppxSW6Tufb5YQ created (provider=None) 2024-11-20 16:36:42,582 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-004] Fetching 7458630270 from sbr:7458630270 to /home/runner/.ya/tools/v4/7458630270 dir, post_process=0) 2024-11-20 16:36:42,582 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/4e54347458416470685f63324c5f4145576f49476c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,582 DEBUG (yalibrary.fetcher.resource_fetcher) [Worker-017] Fetching 7336031045 from sbr:7336031045 to /home/runner/.ya/tools/v4/7336031045 dir, post_process=0) 2024-11-20 16:36:42,582 DEBUG (urllib3.connectionpool) [Worker-011] http://cachesrv.internal:8081 "HEAD /ac/753939734c6869385266476b79365858586a776d4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,583 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing c27g5_HNf-sjH7U9PO-hVA => False 2024-11-20 16:36:42,583 DEBUG (urllib3.connectionpool) [Worker-012] http://cachesrv.internal:8081 "HEAD /ac/7530774b3034394f794755317a74676d487638566a5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,583 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-009] Bazel-remote Probing h8MmIRBiBppxSW6Tufb5YQ => False 2024-11-20 16:36:42,584 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-010] Bazel-remote Probing Tme52Cb5EM4_wrKpsn8FLQ => False 2024-11-20 16:36:42,584 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for HD_DpPu16z3sGxh2I1yfig created (provider=None) 2024-11-20 16:36:42,584 DEBUG (yalibrary.toolscache) [Worker-004] Tools cache attempt: (request for resource 7458630270) 2024-11-20 16:36:42,584 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing NT4tXAdph_c2L_AEWoIGlQ => False 2024-11-20 16:36:42,585 DEBUG (urllib3.connectionpool) [Worker-014] http://cachesrv.internal:8081 "HEAD /ac/5f635a54705579642d544f797841536a69383439437700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,585 DEBUG (yalibrary.toolscache) [Worker-017] Tools cache attempt: (request for resource 7336031045) 2024-11-20 16:36:42,585 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-011] Bazel-remote Probing u99sLhi8RfGky6XXXjwmMQ => False 2024-11-20 16:36:42,586 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-012] Bazel-remote Probing u0wK049OyGU1ztgmHv8VjQ => False 2024-11-20 16:36:42,589 DEBUG (urllib3.connectionpool) [Worker-015] http://cachesrv.internal:8081 "HEAD /ac/7345326e416c376c5f5a57583738653571324b545a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,589 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/2d7054776e4e484c37653736515a4c697071546a367700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:42,589 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for c27g5_HNf-sjH7U9PO-hVA created (provider=None) 2024-11-20 16:36:42,591 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-014] Bazel-remote Probing _cZTpUyd-TOyxASji849Cw => False 2024-11-20 16:36:42,592 DEBUG (yalibrary.toolscache) [Worker-004] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 1 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 16:36:42,597 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-015] Bazel-remote Probing sE2nAl7l_ZWX78e5q2KTZw => False 2024-11-20 16:36:42,598 DEBUG (yalibrary.toolscache) [Worker-017] tc cache stats: TotalKnownSize: 62119936 TotalKnownSizeLocked: 22249472 NonComputedCount: 2 Master: true TotalDBSize: 81920 ToolCount: 2 ProcessesCount: 2 2024-11-20 16:36:42,598 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing -pTwnNHL7e76QZLipqTj6w => False 2024-11-20 16:36:42,598 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for NT4tXAdph_c2L_AEWoIGlQ created (provider=None) 2024-11-20 16:36:42,598 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,599 DEBUG (yalibrary.fetcher.cache_helper) [Worker-004] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 16:36:42,599 DEBUG (yalibrary.fetcher.cache_helper) [Worker-017] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 16:36:42,600 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for Tme52Cb5EM4_wrKpsn8FLQ created (provider=None) 2024-11-20 16:36:42,600 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "HEAD /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,601 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing vcTbvzoc1y5fonnHRGtWQg => True 2024-11-20 16:36:42,602 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for _cZTpUyd-TOyxASji849Cw created (provider=None) 2024-11-20 16:36:42,602 DEBUG (library.python.filelock) [Worker-017] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 16:36:42,602 DEBUG (library.python.filelock) [Worker-004] Acquiring FileLock (blocking=True): /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 16:36:42,602 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-008] Bazel-remote Probing 2vuoGKxpYKfi9H95K457MA => True 2024-11-20 16:36:42,603 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for u99sLhi8RfGky6XXXjwmMQ created (provider=None) 2024-11-20 16:36:42,603 DEBUG (yalibrary.fetcher.cache_helper) [Worker-017] not installed /home/runner/.ya/tools/v4/7336031045 2024-11-20 16:36:42,604 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for u0wK049OyGU1ztgmHv8VjQ created (provider=None) 2024-11-20 16:36:42,604 DEBUG (yalibrary.fetcher.cache_helper) [Worker-004] not installed /home/runner/.ya/tools/v4/7458630270 2024-11-20 16:36:42,604 DEBUG (yalibrary.runner.tasks.result) [Worker-002] ResultsNodeTask for sE2nAl7l_ZWX78e5q2KTZw created (provider=None) 2024-11-20 16:36:42,605 DEBUG (exts.http_client) [Worker-017] Downloading https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.rp5pxcv4.3wwdle5o.part, expect md5- 2024-11-20 16:36:42,605 DEBUG (exts.http_client) [Worker-004] Downloading https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.80qqkd6e.5uwrh56i.part, expect md5- 2024-11-20 16:36:42,605 DEBUG (yalibrary.toolscache) [Worker-002] AC cache attempt: (force gc 2199023255552) 2024-11-20 16:36:42,614 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "HEAD /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:42,614 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /ac/76635462767a6f63317935666f6e6e4852477457516700000000000000000001 HTTP/1.1" 200 576 2024-11-20 16:36:42,614 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /ac/3276756f474b7870594b6669394839354b3435374d4100000000000000000001 HTTP/1.1" 200 570 2024-11-20 16:36:42,620 DEBUG (yalibrary.toolscache) [Worker-002] Tools cache attempt: (force gc tools cache 2199023255552) 2024-11-20 16:36:42,620 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-013] Bazel-remote Probing aKzGxlyWajyio7JTZ8zcfw => True 2024-11-20 16:36:42,630 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/8be96c789171a7f856c4083ecaa4106e6f3f76e1694c212442063022ac08b35a HTTP/1.1" 200 45 2024-11-20 16:36:42,632 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /ac/614b7a47786c7957616a79696f374a545a387a63667700000000000000000001 HTTP/1.1" 200 566 2024-11-20 16:36:42,633 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/f978e50c7ff4e2ce87baa6de18a6ddc3ebe8660cc6a8665702411d948e8a9714 HTTP/1.1" 200 45 2024-11-20 16:36:42,640 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/d1c6050a7ed280771f13bd85d7b7fdacec7313803cb4dff8f118ca77f9a78ebf HTTP/1.1" 200 274 2024-11-20 16:36:42,641 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/b48b46aadc84c69d2daf2135ae401548ebae767c7650356d255db5d6e73dfdd7 HTTP/1.1" 200 45 2024-11-20 16:36:42,642 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/33d0bd2f065e52b1bcd15eeb9f0e84cf75076a1c87ce37a33fc2c34433b99826 HTTP/1.1" 200 277 2024-11-20 16:36:42,649 DEBUG (urllib3.connectionpool) [Worker-008] http://cachesrv.internal:8081 "GET /cas/f7d1ec8734e6b999138921ac1aeecaa475c71b3c3928eb98b80bf1d760c4f9d6 HTTP/1.1" 200 313 2024-11-20 16:36:42,650 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/a3fa2bf341630b12bd0d3ad7b7cf2f61297a6907ebec22a3ac798cc1fe0cb080 HTTP/1.1" 200 272 2024-11-20 16:36:42,651 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid 2vuoGKxpYKfi9H95K457MA) 2024-11-20 16:36:42,651 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "GET /cas/2476989c5d8666119ef3d3a99a385863a228824c24ae377dfa11aa72f2786e98 HTTP/1.1" 200 366 2024-11-20 16:36:42,653 DEBUG (yalibrary.toolscache) [Worker-007] AC cache attempt: (put uid vcTbvzoc1y5fonnHRGtWQg) 2024-11-20 16:36:42,661 DEBUG (urllib3.connectionpool) [Worker-013] http://cachesrv.internal:8081 "GET /cas/1401716041e1f0d70ad8a721b55e0693575271b1701d886964e4bb39e62c5840 HTTP/1.1" 200 375 2024-11-20 16:36:42,662 DEBUG (yalibrary.toolscache) [Worker-013] AC cache attempt: (put uid aKzGxlyWajyio7JTZ8zcfw) 2024-11-20 16:36:42,691 DEBUG (exts.http_client) [Worker-017] Request to https://devtools-registry.s3.yandex.net/7336031045 has headers Server: nginx Date: Wed, 20 Nov 2024 16:36:42 GMT Content-Type: application/octet-stream Content-Length: 12509507 Connection: close Accept-Ranges: bytes Etag: "2e88d3d4d49302ffa7daf9d507d47587-2" Last-Modified: Thu, 24 Oct 2024 17:39:31 GMT X-Amz-Request-Id: 73e0960130f6d1e7 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 16:36:42,782 DEBUG (exts.http_client) [Worker-004] Request to https://devtools-registry.s3.yandex.net/7458630270 has headers Server: nginx Date: Wed, 20 Nov 2024 16:36:42 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: 272d2631d68029d9 NEL: {"report_to": "network-errors", "max_age": 600, "success_fraction": 0.001, "failure_fraction": 0.01} Report-To: {"group": "network-errors", "max_age": 600, "endpoints": [ { "url": "https://dr.yandex.net/s3_nel?datacenter=VLA"}]} Access-Control-Allow-Origin: * X-Robots-Tag: noindex, noarchive, nofollow 2024-11-20 16:36:42,837 DEBUG (exts.http_client) [Worker-017] Downloading finished https://devtools-registry.s3.yandex.net/7336031045 to /home/runner/.ya/tools/v4/7336031045/resource.rp5pxcv4, md5=0721a973ac7d03643308fab89504be06, size=12509507, elapsed=0.231735 2024-11-20 16:36:42,837 DEBUG (yalibrary.fetcher.common) [Worker-017] extract /home/runner/.ya/tools/v4/7336031045/resource.rp5pxcv4 to /home/runner/.ya/tools/v4/7336031045 dir (strip_prefix=None) 2024-11-20 16:36:42,999 DEBUG (library.python.filelock) [Worker-017] Ensuring FileLock released: /home/runner/.ya/tools/v4/7336031045.lock 2024-11-20 16:36:43,002 DEBUG (yalibrary.toolscache) [Worker-017] AC cache attempt: (get uid f31b840a07389e0ee4c03726cdae042e) 2024-11-20 16:36:43,005 DEBUG (yalibrary.toolscache) [Worker-061] AC cache attempt: (get uid f0d297038a92e762846f81c20da06929) 2024-11-20 16:36:43,014 DEBUG (urllib3.connectionpool) [Worker-017] http://cachesrv.internal:8081 "HEAD /ac/e05e801950cb422d268a3c5ea9ac3739f3ec82076fcd4be6be48fda39fcd1511 HTTP/1.1" 404 0 2024-11-20 16:36:43,014 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-017] Bazel-remote Probing f31b840a07389e0ee4c03726cdae042e => False 2024-11-20 16:36:43,014 DEBUG (root) [Worker-017] Run node h8MmIRBiBppxSW6Tufb5YQ in build root /home/runner/.ya/build/build_root/thpb/00000f 2024-11-20 16:36:43,016 DEBUG (urllib3.connectionpool) [Worker-061] http://cachesrv.internal:8081 "HEAD /ac/94bb375964a099a76a15d75f4817d9f0a81a0a5711aa7971293a774d0f003711 HTTP/1.1" 404 0 2024-11-20 16:36:43,016 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-061] Bazel-remote Probing f0d297038a92e762846f81c20da06929 => False 2024-11-20 16:36:43,017 DEBUG (root) [Worker-061] Run node u0wK049OyGU1ztgmHv8VjQ in build root /home/runner/.ya/build/build_root/thpb/000015 2024-11-20 16:36:43,398 DEBUG (yalibrary.toolscache) [Worker-068] AC cache attempt: (put uid h8MmIRBiBppxSW6Tufb5YQ) 2024-11-20 16:36:43,399 DEBUG (yalibrary.runner.tasks.result) [Worker-017] Result node h8MmIRBiBppxSW6Tufb5YQ processed successfully: True 2024-11-20 16:36:43,400 DEBUG (yalibrary.toolscache) [Worker-068] AC cache attempt: (put uid f31b840a07389e0ee4c03726cdae042e) 2024-11-20 16:36:43,405 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "HEAD /ac/68384d6d49524269427070785357365475666235595100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:43,406 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-066] Bazel-remote Probing h8MmIRBiBppxSW6Tufb5YQ => False 2024-11-20 16:36:43,407 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-066] Put ydb/docs/ru/core/docs-ru-core.docslib(h8MmIRBiBppxSW6Tufb5YQ) to Bazel-remote 2024-11-20 16:36:43,417 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "PUT /cas/cc13d9f7072ddf4a0f75ee1372a0b6e026dd01d9603d53aaccf81c458ed9fee3 HTTP/1.1" 200 0 2024-11-20 16:36:43,433 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "PUT /cas/a0fde7150ea360a99b599feb485c3f3bd13d1b4be0103d5f0e4ffc1b4501b30d HTTP/1.1" 200 0 2024-11-20 16:36:43,613 DEBUG (yalibrary.runner.tasks.result) [Worker-061] Result node u0wK049OyGU1ztgmHv8VjQ processed successfully: True 2024-11-20 16:36:43,615 DEBUG (yalibrary.toolscache) [Worker-078] AC cache attempt: (put uid u0wK049OyGU1ztgmHv8VjQ) 2024-11-20 16:36:43,617 DEBUG (yalibrary.toolscache) [Worker-078] AC cache attempt: (put uid f0d297038a92e762846f81c20da06929) 2024-11-20 16:36:43,622 DEBUG (urllib3.connectionpool) [Worker-077] http://cachesrv.internal:8081 "HEAD /ac/7530774b3034394f794755317a74676d487638566a5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:43,622 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-077] Bazel-remote Probing u0wK049OyGU1ztgmHv8VjQ => False 2024-11-20 16:36:43,623 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-077] Put ydb/docs/__docs_internal.docslib.fake(u0wK049OyGU1ztgmHv8VjQ) to Bazel-remote 2024-11-20 16:36:43,633 DEBUG (urllib3.connectionpool) [Worker-077] http://cachesrv.internal:8081 "PUT /cas/e5b97e108bf7d9477a75a0e094ee2293371356b44d99a0ccd33da48bd77fb637 HTTP/1.1" 200 0 2024-11-20 16:36:43,643 DEBUG (urllib3.connectionpool) [Worker-077] http://cachesrv.internal:8081 "PUT /cas/80f6140d5335f1810f35fe7e08e5e6938407e13474ff6210ff4d49e13b7b87db HTTP/1.1" 200 0 2024-11-20 16:36:43,794 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "PUT /cas/4ada6084dd22811b02ccfc78a20519dad4148437f46885ec1514c41ce55545ab HTTP/1.1" 200 0 2024-11-20 16:36:43,804 DEBUG (urllib3.connectionpool) [Worker-066] http://cachesrv.internal:8081 "PUT /ac/68384d6d49524269427070785357365475666235595100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:43,804 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-066] Put ydb/docs/ru/core/docs-ru-core.docslib(h8MmIRBiBppxSW6Tufb5YQ) size=29634833 to Bazel-remote completed 2024-11-20 16:36:44,239 DEBUG (urllib3.connectionpool) [Worker-077] http://cachesrv.internal:8081 "PUT /cas/89bf6b3fc52765a2862935f45c3b1137e6bce315fb8d81811c48ac3cacc38b30 HTTP/1.1" 200 0 2024-11-20 16:36:44,250 DEBUG (urllib3.connectionpool) [Worker-077] http://cachesrv.internal:8081 "PUT /ac/7530774b3034394f794755317a74676d487638566a5100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:44,250 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-077] Put ydb/docs/__docs_internal.docslib.fake(u0wK049OyGU1ztgmHv8VjQ) size=51589393 to Bazel-remote completed 2024-11-20 16:36:50,762 DEBUG (exts.http_client) [Worker-004] Downloading finished https://devtools-registry.s3.yandex.net/7458630270 to /home/runner/.ya/tools/v4/7458630270/resource.80qqkd6e, md5=ee5835f38a9b5ba7d1d91adbaec0d95c, size=411596800, elapsed=8.156035 2024-11-20 16:36:50,763 DEBUG (yalibrary.fetcher.common) [Worker-004] extract /home/runner/.ya/tools/v4/7458630270/resource.80qqkd6e to /home/runner/.ya/tools/v4/7458630270 dir (strip_prefix=None) 2024-11-20 16:36:51,109 DEBUG (library.python.filelock) [Worker-004] Ensuring FileLock released: /home/runner/.ya/tools/v4/7458630270.lock 2024-11-20 16:36:51,111 DEBUG (yalibrary.toolscache) [Worker-004] AC cache attempt: (get uid e21294dd56bbd9042323d3502cbd6403) 2024-11-20 16:36:51,111 DEBUG (yalibrary.toolscache) [Worker-093] AC cache attempt: (get uid 4013c18103f420625740c05fa09468dd) 2024-11-20 16:36:51,112 DEBUG (yalibrary.toolscache) [Worker-094] AC cache attempt: (get uid 7669b88b511bc59ec3b591e6ba947bd1) 2024-11-20 16:36:51,112 DEBUG (yalibrary.toolscache) [Worker-092] AC cache attempt: (get uid 6cb10cc52b3ce255038a51c46a4386e8) 2024-11-20 16:36:51,125 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "HEAD /ac/a5fbd78633da6e5788fcbfe79eaca99652f6dfb730ebb4914b17619fd76cee11 HTTP/1.1" 404 0 2024-11-20 16:36:51,125 DEBUG (urllib3.connectionpool) [Worker-004] http://cachesrv.internal:8081 "HEAD /ac/50e93eae9dc86dfc8bcbe05442e3b8e3a7f6695454095c18b38e2ae05abc1c11 HTTP/1.1" 404 0 2024-11-20 16:36:51,125 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "HEAD /ac/f068fad6d1b1b66a94590126b160bf738a3b9278e3559976611940a2f4b2f311 HTTP/1.1" 404 0 2024-11-20 16:36:51,125 DEBUG (urllib3.connectionpool) [Worker-093] http://cachesrv.internal:8081 "HEAD /ac/af485009343f86ae9f42c9e566604a86561c24aadbcb4927bcf63d8aa58ec111 HTTP/1.1" 404 0 2024-11-20 16:36:51,126 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-092] Bazel-remote Probing 6cb10cc52b3ce255038a51c46a4386e8 => False 2024-11-20 16:36:51,126 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-004] Bazel-remote Probing e21294dd56bbd9042323d3502cbd6403 => False 2024-11-20 16:36:51,126 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Bazel-remote Probing 7669b88b511bc59ec3b591e6ba947bd1 => False 2024-11-20 16:36:51,127 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-093] Bazel-remote Probing 4013c18103f420625740c05fa09468dd => False 2024-11-20 16:36:51,130 DEBUG (root) [Worker-004] Run node c27g5_HNf-sjH7U9PO-hVA in build root /home/runner/.ya/build/build_root/thpb/00000e 2024-11-20 16:36:51,132 DEBUG (root) [Worker-094] Run node -pTwnNHL7e76QZLipqTj6w in build root /home/runner/.ya/build/build_root/thpb/000018 2024-11-20 16:36:51,165 DEBUG (root) [Worker-093] Run node NT4tXAdph_c2L_AEWoIGlQ in build root /home/runner/.ya/build/build_root/thpb/000012 2024-11-20 16:36:51,186 DEBUG (root) [Worker-092] Run node u99sLhi8RfGky6XXXjwmMQ in build root /home/runner/.ya/build/build_root/thpb/000014 2024-11-20 16:36:55,045 DEBUG (yalibrary.toolscache) [Worker-101] AC cache attempt: (put uid -pTwnNHL7e76QZLipqTj6w) 2024-11-20 16:36:55,046 DEBUG (yalibrary.toolscache) [Worker-100] AC cache attempt: (get uid e46d970c0a6e8ec16c408d765f638649) 2024-11-20 16:36:55,047 DEBUG (yalibrary.runner.tasks.result) [Worker-099] Result node -pTwnNHL7e76QZLipqTj6w processed successfully: True 2024-11-20 16:36:55,048 DEBUG (yalibrary.toolscache) [Worker-101] AC cache attempt: (put uid 7669b88b511bc59ec3b591e6ba947bd1) 2024-11-20 16:36:55,052 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "HEAD /ac/2d7054776e4e484c37653736515a4c697071546a367700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:55,052 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Bazel-remote Probing -pTwnNHL7e76QZLipqTj6w => False 2024-11-20 16:36:55,053 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(-pTwnNHL7e76QZLipqTj6w) to Bazel-remote 2024-11-20 16:36:55,056 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/ee7bd622c65e8d1810651183c1ecc59a40c32688e8162a14d0e297594a8f6e11 HTTP/1.1" 404 0 2024-11-20 16:36:55,056 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing e46d970c0a6e8ec16c408d765f638649 => False 2024-11-20 16:36:55,056 DEBUG (root) [Worker-100] Run node Tme52Cb5EM4_wrKpsn8FLQ in build root /home/runner/.ya/build/build_root/thpb/000010 2024-11-20 16:36:55,065 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/3a8cb1fa99c15f03d9698c4add863df67c6129373f5bfe2624eff8d035c2af0b HTTP/1.1" 200 0 2024-11-20 16:36:55,069 DEBUG (yalibrary.toolscache) [Worker-110] AC cache attempt: (put uid c27g5_HNf-sjH7U9PO-hVA) 2024-11-20 16:36:55,070 DEBUG (yalibrary.toolscache) [Worker-113] AC cache attempt: (get uid 0802742ea6c7bde8d7f1d2ef3f42c8dc) 2024-11-20 16:36:55,071 DEBUG (yalibrary.toolscache) [Worker-110] AC cache attempt: (put uid e21294dd56bbd9042323d3502cbd6403) 2024-11-20 16:36:55,071 DEBUG (yalibrary.runner.tasks.result) [Worker-004] Result node c27g5_HNf-sjH7U9PO-hVA processed successfully: True 2024-11-20 16:36:55,076 DEBUG (urllib3.connectionpool) [Worker-112] http://cachesrv.internal:8081 "HEAD /ac/63323767355f484e662d736a48375539504f2d68564100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:55,076 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-112] Bazel-remote Probing c27g5_HNf-sjH7U9PO-hVA => False 2024-11-20 16:36:55,077 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-112] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(c27g5_HNf-sjH7U9PO-hVA) to Bazel-remote 2024-11-20 16:36:55,078 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/0ffe85de644ec1e81d4e422262df0078ea30487f5de2228669e808d42ba09778 HTTP/1.1" 200 0 2024-11-20 16:36:55,079 DEBUG (urllib3.connectionpool) [Worker-113] http://cachesrv.internal:8081 "HEAD /ac/81e2838982d585d59b157eeb10badd2ba365818925959660b382f60a09e5cb11 HTTP/1.1" 404 0 2024-11-20 16:36:55,080 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-113] Bazel-remote Probing 0802742ea6c7bde8d7f1d2ef3f42c8dc => False 2024-11-20 16:36:55,080 DEBUG (root) [Worker-113] Run node HD_DpPu16z3sGxh2I1yfig in build root /home/runner/.ya/build/build_root/thpb/00000d 2024-11-20 16:36:55,087 DEBUG (urllib3.connectionpool) [Worker-112] http://cachesrv.internal:8081 "PUT /cas/36b93a3aed0abccb8ab1c0c3b29f09dfb57f44252e1bbd3b84edcb4458c19d63 HTTP/1.1" 200 0 2024-11-20 16:36:55,098 DEBUG (urllib3.connectionpool) [Worker-112] http://cachesrv.internal:8081 "PUT /cas/c3adb264867c16811c89fe00ac49f5790490ebafaa266437b5132a70e4bb0abf HTTP/1.1" 200 0 2024-11-20 16:36:55,144 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /cas/0b9beb4660a93695aa89d772cf0541ae3fd0d8ad1fce6b08f9d245666e68b1a5 HTTP/1.1" 200 0 2024-11-20 16:36:55,155 DEBUG (urllib3.connectionpool) [Worker-094] http://cachesrv.internal:8081 "PUT /ac/2d7054776e4e484c37653736515a4c697071546a367700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:55,155 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-094] Put ydb/yql_docs/tests/build/yt/preprocessed.tar.gz(-pTwnNHL7e76QZLipqTj6w) size=1092281 to Bazel-remote completed 2024-11-20 16:36:55,165 DEBUG (urllib3.connectionpool) [Worker-112] http://cachesrv.internal:8081 "PUT /cas/295f652d1f5a242f65c016345250487c37a82a1e3324fa44a777331281ecf65b HTTP/1.1" 200 0 2024-11-20 16:36:55,175 DEBUG (urllib3.connectionpool) [Worker-112] http://cachesrv.internal:8081 "PUT /ac/63323767355f484e662d736a48375539504f2d68564100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:55,175 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-112] Put ydb/yql_docs/tests/build/default/preprocessed.tar.gz(c27g5_HNf-sjH7U9PO-hVA) size=1092256 to Bazel-remote completed 2024-11-20 16:36:55,260 DEBUG (yalibrary.toolscache) [Worker-129] AC cache attempt: (put uid NT4tXAdph_c2L_AEWoIGlQ) 2024-11-20 16:36:55,261 DEBUG (yalibrary.toolscache) [Worker-130] AC cache attempt: (get uid b33dd81529f1073c96a2f75a6bfebbd9) 2024-11-20 16:36:55,262 DEBUG (yalibrary.runner.tasks.result) [Worker-126] Result node NT4tXAdph_c2L_AEWoIGlQ processed successfully: True 2024-11-20 16:36:55,262 DEBUG (yalibrary.toolscache) [Worker-129] AC cache attempt: (put uid 4013c18103f420625740c05fa09468dd) 2024-11-20 16:36:55,267 DEBUG (urllib3.connectionpool) [Worker-093] http://cachesrv.internal:8081 "HEAD /ac/4e54347458416470685f63324c5f4145576f49476c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:36:55,267 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-093] Bazel-remote Probing NT4tXAdph_c2L_AEWoIGlQ => False 2024-11-20 16:36:55,268 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(NT4tXAdph_c2L_AEWoIGlQ) to Bazel-remote 2024-11-20 16:36:55,271 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "HEAD /ac/8ded51fae304b642e06ab3cdd787930bc7b3c138264d68825c370610b259d411 HTTP/1.1" 404 0 2024-11-20 16:36:55,271 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-130] Bazel-remote Probing b33dd81529f1073c96a2f75a6bfebbd9 => False 2024-11-20 16:36:55,271 DEBUG (root) [Worker-130] Run node sE2nAl7l_ZWX78e5q2KTZw in build root /home/runner/.ya/build/build_root/thpb/000017 2024-11-20 16:36:55,279 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/ed99f011253e605f40fc0b0aebd56d7768e84fc27b262b996a4ad9cd0290c7ac HTTP/1.1" 200 0 2024-11-20 16:36:55,291 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/caa37af152423022b3d1caee5fb30f05a72195a2b4259364584eb21adff1210e HTTP/1.1" 200 0 2024-11-20 16:36:55,323 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /cas/5b3e892739cc1b98af7a55f08b8ff485ebb76fb26b155c639e24b72e5a2c42ee HTTP/1.1" 200 0 2024-11-20 16:36:55,333 DEBUG (urllib3.connectionpool) [Worker-136] http://cachesrv.internal:8081 "PUT /ac/4e54347458416470685f63324c5f4145576f49476c5100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:36:55,333 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-136] Put ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz(NT4tXAdph_c2L_AEWoIGlQ) size=1092285 to Bazel-remote completed 2024-11-20 16:37:00,842 DEBUG (yalibrary.toolscache) [Worker-001] AC cache attempt: (put uid Tme52Cb5EM4_wrKpsn8FLQ) 2024-11-20 16:37:00,843 DEBUG (yalibrary.runner.tasks.result) [Worker-003] Result node Tme52Cb5EM4_wrKpsn8FLQ processed successfully: True 2024-11-20 16:37:00,844 DEBUG (yalibrary.toolscache) [Worker-001] AC cache attempt: (put uid e46d970c0a6e8ec16c408d765f638649) 2024-11-20 16:37:00,849 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "HEAD /ac/546d653532436235454d345f77724b70736e38464c5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:37:00,849 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Bazel-remote Probing Tme52Cb5EM4_wrKpsn8FLQ => False 2024-11-20 16:37:00,850 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(Tme52Cb5EM4_wrKpsn8FLQ) to Bazel-remote 2024-11-20 16:37:00,861 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/1fc6749ebf1ad57817b94931e1da69c14dbbd67b01e78be1bc81f0b1dd02604e HTTP/1.1" 200 0 2024-11-20 16:37:00,872 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/0a92af697d227c1dcf504b2501bc441001e2827deb5e8c8cfb2d5f4642e4cfea HTTP/1.1" 200 0 2024-11-20 16:37:00,949 DEBUG (yalibrary.toolscache) [Worker-010] AC cache attempt: (put uid HD_DpPu16z3sGxh2I1yfig) 2024-11-20 16:37:00,951 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /cas/1a4f853eceda24b2a674ddf0a02c10a7c691da2ca2df2a9290ebe0704b6203bf HTTP/1.1" 200 0 2024-11-20 16:37:00,952 DEBUG (yalibrary.toolscache) [Worker-010] AC cache attempt: (put uid 0802742ea6c7bde8d7f1d2ef3f42c8dc) 2024-11-20 16:37:00,953 DEBUG (yalibrary.runner.tasks.result) [Worker-009] Result node HD_DpPu16z3sGxh2I1yfig processed successfully: True 2024-11-20 16:37:00,956 DEBUG (urllib3.connectionpool) [Worker-113] http://cachesrv.internal:8081 "HEAD /ac/48445f4470507531367a33734778683249317966696700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:37:00,956 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-113] Bazel-remote Probing HD_DpPu16z3sGxh2I1yfig => False 2024-11-20 16:37:00,956 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-028] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(HD_DpPu16z3sGxh2I1yfig) to Bazel-remote 2024-11-20 16:37:00,961 DEBUG (urllib3.connectionpool) [Worker-100] http://cachesrv.internal:8081 "PUT /ac/546d653532436235454d345f77724b70736e38464c5100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:37:00,961 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-100] Put ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz(Tme52Cb5EM4_wrKpsn8FLQ) size=2779897 to Bazel-remote completed 2024-11-20 16:37:00,967 DEBUG (urllib3.connectionpool) [Worker-028] http://cachesrv.internal:8081 "PUT /cas/0890e05b4636437f890f8332f4d7e278ff484eab3759865a3231f73907f05fcc HTTP/1.1" 200 0 2024-11-20 16:37:00,978 DEBUG (urllib3.connectionpool) [Worker-028] http://cachesrv.internal:8081 "PUT /cas/cc19dc0e456f581adf56322b2316dccb4ea2664716e977817aa368be58d87db7 HTTP/1.1" 200 0 2024-11-20 16:37:01,066 DEBUG (urllib3.connectionpool) [Worker-028] http://cachesrv.internal:8081 "PUT /cas/332c54077b4303f3a85c6506bb46b4e6b9110481349695abfecd7e03a75d79c5 HTTP/1.1" 200 0 2024-11-20 16:37:01,077 DEBUG (urllib3.connectionpool) [Worker-028] http://cachesrv.internal:8081 "PUT /ac/48445f4470507531367a33734778683249317966696700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:37:01,078 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-028] Put ydb/yql_docs/tests/build/default/tests-build-default.tar.gz(HD_DpPu16z3sGxh2I1yfig) size=2779917 to Bazel-remote completed 2024-11-20 16:37:01,260 DEBUG (yalibrary.toolscache) [Worker-035] AC cache attempt: (put uid sE2nAl7l_ZWX78e5q2KTZw) 2024-11-20 16:37:01,262 DEBUG (yalibrary.runner.tasks.result) [Worker-011] Result node sE2nAl7l_ZWX78e5q2KTZw processed successfully: True 2024-11-20 16:37:01,262 DEBUG (yalibrary.toolscache) [Worker-035] AC cache attempt: (put uid b33dd81529f1073c96a2f75a6bfebbd9) 2024-11-20 16:37:01,267 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "HEAD /ac/7345326e416c376c5f5a57583738653571324b545a7700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:37:01,268 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-130] Bazel-remote Probing sE2nAl7l_ZWX78e5q2KTZw => False 2024-11-20 16:37:01,268 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-130] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(sE2nAl7l_ZWX78e5q2KTZw) to Bazel-remote 2024-11-20 16:37:01,279 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "PUT /cas/c75a63d620af3cbbbc5e04b57bc142e8a58be591b4fca6df51fca3c5225c0cd9 HTTP/1.1" 200 0 2024-11-20 16:37:01,290 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "PUT /cas/f910aa40b2626a4a4fb38025a64c885b1bc9d20897a26e4e713622bf5d38e3c7 HTTP/1.1" 200 0 2024-11-20 16:37:01,326 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "PUT /cas/5bde639bc503ad08379c96a5f7c11d94270bc52ab48308ecdbc46c45e307c87f HTTP/1.1" 200 0 2024-11-20 16:37:01,336 DEBUG (urllib3.connectionpool) [Worker-130] http://cachesrv.internal:8081 "PUT /ac/7345326e416c376c5f5a57583738653571324b545a7700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:37:01,337 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-130] Put ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz(sE2nAl7l_ZWX78e5q2KTZw) size=2779905 to Bazel-remote completed 2024-11-20 16:37:13,034 DEBUG (yalibrary.toolscache) [Worker-046] AC cache attempt: (put uid u99sLhi8RfGky6XXXjwmMQ) 2024-11-20 16:37:13,035 DEBUG (yalibrary.toolscache) [Worker-043] AC cache attempt: (get uid a779a5ade4437e2856bda0b22dc90673) 2024-11-20 16:37:13,036 DEBUG (yalibrary.runner.tasks.result) [Worker-044] Result node u99sLhi8RfGky6XXXjwmMQ processed successfully: True 2024-11-20 16:37:13,036 DEBUG (yalibrary.toolscache) [Worker-046] AC cache attempt: (put uid 6cb10cc52b3ce255038a51c46a4386e8) 2024-11-20 16:37:13,041 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "HEAD /ac/753939734c6869385266476b79365858586a776d4d5100000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:37:13,041 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-092] Bazel-remote Probing u99sLhi8RfGky6XXXjwmMQ => False 2024-11-20 16:37:13,042 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-092] Put ydb/docs/preprocessed.tar.gz(u99sLhi8RfGky6XXXjwmMQ) to Bazel-remote 2024-11-20 16:37:13,045 DEBUG (urllib3.connectionpool) [Worker-043] http://cachesrv.internal:8081 "HEAD /ac/bb83d65c475d02e65a045a637ece42ed26d7df4c6cad638faf09f99f7d7ddc11 HTTP/1.1" 404 0 2024-11-20 16:37:13,045 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-043] Bazel-remote Probing a779a5ade4437e2856bda0b22dc90673 => False 2024-11-20 16:37:13,045 DEBUG (root) [Worker-043] Run node _cZTpUyd-TOyxASji849Cw in build root /home/runner/.ya/build/build_root/thpb/000016 2024-11-20 16:37:13,052 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "PUT /cas/e8b405f028c28d16071b5174be106b2ad174a72139e05834ab64a7cfc335b3b6 HTTP/1.1" 200 0 2024-11-20 16:37:13,062 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "PUT /cas/06ce20589fa6841a76d6d97571d7cec3e6c9f9721ebab06f911a7b2ced714b8e HTTP/1.1" 200 0 2024-11-20 16:37:13,430 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "PUT /cas/36a5588d0ccfd77ef8e1d0fcec2213c103c61ecea6963566124f21240ed3990b HTTP/1.1" 200 0 2024-11-20 16:37:13,440 DEBUG (urllib3.connectionpool) [Worker-092] http://cachesrv.internal:8081 "PUT /ac/753939734c6869385266476b79365858586a776d4d5100000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:37:13,440 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-092] Put ydb/docs/preprocessed.tar.gz(u99sLhi8RfGky6XXXjwmMQ) size=36209130 to Bazel-remote completed 2024-11-20 16:38:03,959 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid _cZTpUyd-TOyxASji849Cw) 2024-11-20 16:38:03,959 DEBUG (yalibrary.runner.tasks.result) [Worker-043] Result node _cZTpUyd-TOyxASji849Cw processed successfully: True 2024-11-20 16:38:03,961 DEBUG (yalibrary.toolscache) [Worker-008] AC cache attempt: (put uid a779a5ade4437e2856bda0b22dc90673) 2024-11-20 16:38:03,966 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "HEAD /ac/5f635a54705579642d544f797841536a69383439437700000000000000000001 HTTP/1.1" 404 0 2024-11-20 16:38:03,966 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Bazel-remote Probing _cZTpUyd-TOyxASji849Cw => False 2024-11-20 16:38:03,967 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Put ydb/docs/ydb-docs.tar.gz(_cZTpUyd-TOyxASji849Cw) to Bazel-remote 2024-11-20 16:38:03,978 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "PUT /cas/f343cd8449be900e9ede48348e824a024caa8b39d2f3f78ac8f6a2ec1a2e6747 HTTP/1.1" 200 0 2024-11-20 16:38:03,992 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "PUT /cas/2fa2be78d641b99936b8dee102de526335df67e93ffbe3ed9fcadd1124a75b2b HTTP/1.1" 200 0 2024-11-20 16:38:04,428 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "PUT /cas/db5a2f30f6f335c46b7c09b2b49a8b2e80c4de51bfc7a5712dff6eae698c3e2f HTTP/1.1" 200 0 2024-11-20 16:38:04,438 DEBUG (urllib3.connectionpool) [Worker-007] http://cachesrv.internal:8081 "PUT /ac/5f635a54705579642d544f797841536a69383439437700000000000000000001 HTTP/1.1" 200 0 2024-11-20 16:38:04,438 DEBUG (yalibrary.store.bazel_store.bazel_store) [Worker-007] Put ydb/docs/ydb-docs.tar.gz(_cZTpUyd-TOyxASji849Cw) size=39678516 to Bazel-remote completed 2024-11-20 16:38:04,508 DEBUG (yalibrary.runner.build_root) [Worker-063] Build root /home/runner/.ya/build/build_root/thpb created=25 left=12 2024-11-20 16:38:04,508 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/build_root/thpb/STAMP 2024-11-20 16:38:04,520 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(-pTwnNHL7e76QZLipqTj6w$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-20 16:38:04,520 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(c27g5_HNf-sjH7U9PO-hVA$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file index.md COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query. ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-20 16:38:04,520 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(NT4tXAdph_c2L_AEWoIGlQ$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file toc.yaml COPY Copying file ya.make COPY Copying file ydb/docs/ru/core/_assets/embedded_query.png COPY Copying file ydb/d ... md WARN Variable backend_name not found in /builtins/_includes/aggregation/max_min_by.md WARN Variable ydb-short-name not found in /udf/list/_includes/topg.md 2024-11-20 16:38:04,520 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(Tme52Cb5EM4_wrKpsn8FLQ$(BUILD_ROOT)/ydb/yql_docs/tests/build/yt/tests-build-yt.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-20 16:38:04,520 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(HD_DpPu16z3sGxh2I1yfig$(BUILD_ROOT)/ydb/yql_docs/tests/build/default/tests-build-default.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-20 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(sE2nAl7l_ZWX78e5q2KTZw$(BUILD_ROOT)/ydb/yql_docs/tests/build/rtmr/tests-build-rtmr.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/KaTeX_AMS-Regular-CYEKBG2K.woff COPY Copying file _assets/KaTeX_AMS-Regular-JKX5W2C4.ttf COPY Copying file _asse ... : 100; Actual: 125] [Context: "Unicode::SplitToList( string:Utf8?, separator:Utf8, [ DelimeterString:Bool?, SkipEmpty:Bool?, Limit:Uint64? ]) -> List"] 2024-11-20 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(u99sLhi8RfGky6XXXjwmMQ$(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz) has stderr: Using v4.44.1 version COPY Copying file _assets/gtm.js COPY Copying file _assets/logo.svg COPY Copying file _assets/tags.css COPY Copying file _assets/ydb-promo ... d: ru/changelog-server.md INFO Processing finished: ru/changelog-cli.md INFO Processing finished: ru/security-changelog.md COPY Copying file _assets/tags.css 2024-11-20 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Task Run(_cZTpUyd-TOyxASji849Cw$(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 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Merged exit code: 0 2024-11-20 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Profile of graph execution {"by_type":{"clean_symres":{"sum":0.004990577697754,"qty":1},"prepare_all_nodes":{"sum":0.044018507003784,"qty":1},"compact_cache":{"sum":0.018430233001709,"qty":1},"restore_from_dist_cache[DO]":{"sum":0.377373695373535,"qty":13},"prepare[DO]":{"sum":0.00523042678833,"qty":3},"put_in_cache[DO]":{"sum":0.042856454849243,"qty":13},"pattern[YMAKE_PYTHON3-4256832079]":{"sum":0.417054414749146,"qty":1},"DO":{"sum":103.563367605209351,"qty":10},"result[DO]":{"sum":0.025675773620605,"qty":10},"write_through_caches[DO]":{"sum":0.088879585266113,"qty":10},"put_in_dist_cache[DO]":{"sum":2.751209497451782,"qty":10},"pattern[YFM_TOOL-2315971726]":{"sum":8.527773857116699,"qty":1},"clean_build_root":{"sum":0.000293731689453,"qty":1}},"all":{"qty":75,"sum":115.867154359817505},"critical_path":[{"name":"Pattern(YFM_TOOL-2315971726)","timing":[1732120602.582105398178101,1732120611.1098792552948],"type":"pattern[YFM_TOOL-2315971726]"}],"wall_time":81.959057092666626,"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 16:38:04,521 DEBUG (yalibrary.runner.runner3) [MainThread] Average compression ratio: 1.00 2024-11-20 16:38:04,521 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dispatch_build, group=ya_make, time=1732120684.5217311 2024-11-20 16:38:04,521 DEBUG (core.stages_profiler) [MainThread] Set stage dispatch_build_finished=1732120684.5217311 2024-11-20 16:38:04,521 DEBUG (core.profiler) [MainThread] Profile step dispatch_build_finished - 1732120684.5217311 2024-11-20 16:38:04,522 DEBUG (core.stage_tracer) [MainThread] Start stage tag=cache_test_statuses, group=ya_make, time=1732120684.5221512 2024-11-20 16:38:04,522 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_started=1732120684.5221512 2024-11-20 16:38:04,522 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_started - 1732120684.5221512 2024-11-20 16:38:04,538 DEBUG (yalibrary.store.new_store) [MainThread] Initialized store in /home/runner/.ya/build/cache/trc 2024-11-20 16:38:04,538 DEBUG (yalibrary.store.new_store) [MainThread] Started filtering 2024-11-20 16:38:04,538 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,538 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,539 DEBUG (yalibrary.store.new_store) [MainThread] Done filtering, retain 0 uids, 0 files 2024-11-20 16:38:04,539 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning size store 2024-11-20 16:38:04,539 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning file store 2024-11-20 16:38:04,541 DEBUG (yalibrary.store.new_store) [MainThread] Cleaning uid store 2024-11-20 16:38:04,549 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,549 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,551 DEBUG (library.python.filelock) [MainThread] Acquiring FileLock (blocking=True): /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,551 DEBUG (library.python.filelock) [MainThread] Ensuring FileLock released: /home/runner/.ya/build/cache/trc/lru/queue/consume.lock 2024-11-20 16:38:04,551 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=cache_test_statuses, group=ya_make, time=1732120684.551205 2024-11-20 16:38:04,551 DEBUG (core.stages_profiler) [MainThread] Set stage cache_test_statuses_finished=1732120684.551205 2024-11-20 16:38:04,551 DEBUG (core.profiler) [MainThread] Profile step cache_test_statuses_finished - 1732120684.551205 2024-11-20 16:38:04,551 DEBUG (yalibrary.toolscache) [MainThread] Tools cache attempt: (request for tc stats) 2024-11-20 16:38:04,552 DEBUG (yalibrary.toolscache) [MainThread] tc stats: TotalKnownSize: 39870464 NonComputedCount: 2 2024-11-20 16:38:04,552 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (request for ac stats) 2024-11-20 16:38:04,553 DEBUG (yalibrary.toolscache) [MainThread] ac stats: TotalFSSize: 168861696 TotalSize: 168759133 2024-11-20 16:38:04,553 DEBUG (core.stage_tracer) [MainThread] Start stage tag=statistics, group=statistics, time=1732120684.5533485 2024-11-20 16:38:04,553 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_started=1732120684.5533485 2024-11-20 16:38:04,553 DEBUG (core.profiler) [MainThread] Profile step statistics_started - 1732120684.5533485 2024-11-20 16:38:04,553 DEBUG (build.stat.graph) [MainThread] Node count in the dependency graph is 63. 2024-11-20 16:38:04,554 DEBUG (build.stat.graph) [MainThread] Dependency count in the graph is 11. 2024-11-20 16:38:04,554 DEBUG (build.stat.statistics) [MainThread] Run tasks 13: 13 cached tasks (0 cache(s) resolved by dynamic uids), 10 not cached, 0 failed 2024-11-20 16:38:04,554 DEBUG (build.stat.statistics) [MainThread] Not cached 10: 0 tests, 10 not tests 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_cache_hit - 56.52173913043478 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_run_tasks - 13 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_executed_tasks - 23 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_cached_tasks - 13 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_dyn_cached_tasks - 0 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_not_cached_tasks - 10 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_tasks - 0 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_tasks - 0 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_ok_tasks - 10 2024-11-20 16:38:04,554 DEBUG (core.profiler) [MainThread] Profile value statistics_avoided_tasks - -10 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value critical_path - [["DO | $(BUILD_ROOT)/ydb/docs/__docs_internal.docslib.fake",586,"local"],["DO | $(BUILD_ROOT)/ydb/docs/preprocessed.tar.gz",21824,"local"],["DO | $(BUILD_ROOT)/ydb/docs/ydb-docs.tar.gz",50888,"local"]] 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_total_time - 80919 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_time - 73298 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_copying_time - 0 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_testing_time - 0 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_compiling_time - 73298 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_source_time - 0 2024-11-20 16:38:04,555 DEBUG (core.profiler) [MainThread] Profile value statistics_graph_tests_data_time - 0 2024-11-20 16:38:04,556 DEBUG (core.profiler) [MainThread] Profile value statistics_longest_task - 50888 2024-11-20 16:38:04,556 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time_ratio - 0.0 2024-11-20 16:38:04,556 DEBUG (core.profiler) [MainThread] Profile value statistics_failed_task_time - 0 2024-11-20 16:38:04,557 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time_ratio - 0.0 2024-11-20 16:38:04,557 DEBUG (core.profiler) [MainThread] Profile value statistics_tests_task_time - 0 2024-11-20 16:38:04,557 DEBUG (core.profiler) [MainThread] Profile value statistics_run_task_time - 103228 2024-11-20 16:38:04,557 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=statistics, group=statistics, time=1732120684.5579195 2024-11-20 16:38:04,558 DEBUG (core.stages_profiler) [MainThread] Set stage statistics_finished=1732120684.5579195 2024-11-20 16:38:04,558 DEBUG (core.profiler) [MainThread] Profile step statistics_finished - 1732120684.5579195 2024-11-20 16:38:04,558 DEBUG (core.stage_tracer) [MainThread] Start stage tag=finalize-reports, group=ya_make, time=1732120684.5585492 2024-11-20 16:38:04,558 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_started=1732120684.5585492 2024-11-20 16:38:04,558 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_started - 1732120684.5585492 2024-11-20 16:38:04,558 DEBUG (build.ya_make) [MainThread] Build is finished, process results 2024-11-20 16:38:04,558 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 3 entries 2024-11-20 16:38:04,558 DEBUG (ReportGenerator) [MainThread] Finish build report 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish build report 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish tests report, tests by size defaultdict(, {'small': set(), 'medium': set(), 'large': set()}), tests by type defaultdict(, {}) 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish tests report 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish style report 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish style report 2024-11-20 16:38:04,559 DEBUG (ReportGenerator) [MainThread] Finish report 2024-11-20 16:38:04,559 DEBUG (StoredReport) [MainThread] Finish report 2024-11-20 16:38:04,559 DEBUG (build.ya_make) [MainThread] Build results listener statistics: {'TestNodeListener': {'duration_sec': 0.00010538101196289062}, 'BuildResultsListener': {'duration_sec': 0.0015025138854980469}} 2024-11-20 16:38:04,559 DEBUG (build.ya_make) [MainThread] Generating results report 2024-11-20 16:38:04,559 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 16:38:04,560 DEBUG (build.reports.autocheck_report) [MainThread] Got 0 tests to report 2024-11-20 16:38:04,560 DEBUG (ReportGenerator) [MainThread] Finish tests report by size small 2024-11-20 16:38:04,560 DEBUG (StoredReport) [MainThread] Finish tests report by size small 2024-11-20 16:38:04,560 DEBUG (ReportGenerator) [MainThread] Finish tests report by size medium 2024-11-20 16:38:04,560 DEBUG (StoredReport) [MainThread] Finish tests report by size medium 2024-11-20 16:38:04,560 DEBUG (ReportGenerator) [MainThread] Finish tests report by size large 2024-11-20 16:38:04,560 DEBUG (StoredReport) [MainThread] Finish tests report by size large 2024-11-20 16:38:04,560 DEBUG (ReportGenerator) [MainThread] Build result is processed, add 13 entries 2024-11-20 16:38:04,561 DEBUG (build.reports.autocheck_report) [MainThread] builder.opts.report_skipped_suites: False, builder.opts.report_skipped_suites False 2024-11-20 16:38:04,561 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 16:38:04,561 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=finalize-reports, group=ya_make, time=1732120684.5614257 2024-11-20 16:38:04,561 DEBUG (core.stages_profiler) [MainThread] Set stage finalize-reports_finished=1732120684.5614257 2024-11-20 16:38:04,561 DEBUG (core.profiler) [MainThread] Profile step finalize-reports_finished - 1732120684.5614257 2024-11-20 16:38:04,562 DEBUG (yalibrary.toolscache) [MainThread] AC cache attempt: (release all data) 2024-11-20 16:38:04,562 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=build, group=build_handler, time=1732120684.5625582 2024-11-20 16:38:04,562 DEBUG (core.stages_profiler) [MainThread] Set stage build_finished=1732120684.5625582 2024-11-20 16:38:04,562 DEBUG (core.profiler) [MainThread] Profile step build_finished - 1732120684.5625582 2024-11-20 16:38:04,562 DEBUG (core.stage_tracer) [MainThread] Start stage tag=dump_results, group=build_handler, time=1732120684.562759 2024-11-20 16:38:04,562 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_started=1732120684.562759 2024-11-20 16:38:04,562 DEBUG (core.profiler) [MainThread] Profile step dump_results_started - 1732120684.562759 2024-11-20 16:38:04,563 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=dump_results, group=build_handler, time=1732120684.5634003 2024-11-20 16:38:04,563 DEBUG (core.stages_profiler) [MainThread] Set stage dump_results_finished=1732120684.5634003 2024-11-20 16:38:04,563 DEBUG (core.profiler) [MainThread] Profile step dump_results_finished - 1732120684.5634003 2024-11-20 16:38:04,563 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=ya_make_handler, group=build_handler, time=1732120684.5636065 2024-11-20 16:38:04,563 DEBUG (core.stages_profiler) [MainThread] Set stage ya_make_handler_finished=1732120684.5636065 2024-11-20 16:38:04,563 DEBUG (core.profiler) [MainThread] Profile step ya_make_handler_finished - 1732120684.5636065 2024-11-20 16:38:04,563 DEBUG (core.stage_tracer) [MainThread] Finish stage tag=invoke-do_ya_make, group=overall-execution, time=1732120684.5638006 2024-11-20 16:38:04,563 DEBUG (core.stages_profiler) [MainThread] Set stage invoke-do_ya_make_finished=1732120684.5638006 2024-11-20 16:38:04,563 DEBUG (core.profiler) [MainThread] Profile step invoke-do_ya_make_finished - 1732120684.5638006 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "dump_debug" stopped in 0.000097 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "evlog" stopped in 0.000030 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "changelist_store" stopped in 0.000002 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "event_queue" stopped in 0.000003 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "mlockall" stopped in 0.000004 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "profile" stopped in 0.000003 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "showstack" stopped in 0.000018 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher" stopped in 0.000002 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000002 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token2" stopped in 0.000003 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "docker_config_path" stopped in 0.000001 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "use_universal_fetcher_everywhere" stopped in 0.000001 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetcher_params" stopped in 0.000008 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "self_info" stopped in 0.000001 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000003 2024-11-20 16:38:04,564 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_log" stopped in 0.000005 2024-11-20 16:38:04,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "custom_file_log" stopped in 0.000009 2024-11-20 16:38:04,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display" stopped in 0.000015 2024-11-20 16:38:04,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "state" stopped in 0.000008 2024-11-20 16:38:04,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "hide_token" stopped in 0.000002 2024-11-20 16:38:04,565 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "params" stopped in 0.000001 2024-11-20 16:38:04,565 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 16:38:04,565 DEBUG (library.python.tmp) [MainThread] Removing tmp dir /home/runner/.ya/tmp/1732120600.2963.pf7n3429 2024-11-20 16:38:04,565 DEBUG (library.python.tmp) [MainThread] Reset back TMPDIR=None 2024-11-20 16:38:04,566 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "tmp_dir" stopped in 0.000641 2024-11-20 16:38:04,566 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "fetchers_storage" stopped in 0.000010 2024-11-20 16:38:04,566 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "env_checker" stopped in 0.000006 2024-11-20 16:38:04,566 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "lifecycle_ts" stopped in 0.000006 2024-11-20 16:38:04,566 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "exit" stopped in 0.000009 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "report" stopped in 0.001220 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "vcs_type" stopped in 0.000009 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "revision" stopped in 0.000005 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_in_memory_log" stopped in 0.000007 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "display_in_memory_log" stopped in 0.000005 2024-11-20 16:38:04,567 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "file_log" stopped in 0.000008 2024-11-20 16:38:04,568 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Module "uid" stopped in 0.000004 2024-11-20 16:38:04,568 DEBUG (devtools.ya.yalibrary.app_ctx) [MainThread] Restored ctx dict_keys([])