// MIT License // // Copyright (c) 2022-2025 Advanced Micro Devices, Inc. All Rights Reserved. // // Permission is hereby granted, free of charge, to any person obtaining a copy // of this software and associated documentation files (the "Software"), to deal // in the Software without restriction, including without limitation the rights // to use, copy, modify, merge, publish, distribute, sublicense, and/or sell // copies of the Software, and to permit persons to whom the Software is // furnished to do so, subject to the following conditions: // // The above copyright notice and this permission notice shall be included in all // copies or substantial portions of the Software. // // THE SOFTWARE IS PROVIDED "AS IS", WITHOUT WARRANTY OF ANY KIND, EXPRESS OR // IMPLIED, INCLUDING BUT NOT LIMITED TO THE WARRANTIES OF MERCHANTABILITY, // FITNESS FOR A PARTICULAR PURPOSE AND NONINFRINGEMENT. IN NO EVENT SHALL THE // AUTHORS OR COPYRIGHT HOLDERS BE LIABLE FOR ANY CLAIM, DAMAGES OR OTHER // LIABILITY, WHETHER IN AN ACTION OF CONTRACT, TORT OR OTHERWISE, ARISING FROM, // OUT OF OR IN CONNECTION WITH THE SOFTWARE OR THE USE OR OTHER DEALINGS IN THE // SOFTWARE. #include // // above should always be included first // #include "api.hpp" #include "common/setup.hpp" #include "common/static_object.hpp" #include "core/agent.hpp" #include "core/agent_manager.hpp" #include "core/categories.hpp" #include "core/components/fwd.hpp" #include "core/concepts.hpp" #include "core/config.hpp" #include "core/constraint.hpp" #include "core/cpu.hpp" #include "core/defines.hpp" #include "core/dynamic_library.hpp" #include "core/gpu.hpp" #include "core/locking.hpp" #include "core/node_info.hpp" #include "core/perfetto_fwd.hpp" #include "core/rocpd/data_processor.hpp" #include "core/timemory.hpp" #include "core/trace_cache/cache_manager.hpp" #include "core/trace_cache/cacheable.hpp" #include "core/trace_cache/metadata_registry.hpp" #include "core/utility.hpp" #include "library/causal/data.hpp" #include "library/causal/experiment.hpp" #include "library/causal/sampling.hpp" #include "library/components/exit_gotcha.hpp" #include "library/components/fork_gotcha.hpp" #include "library/components/mpi_gotcha.hpp" #include "library/components/numa_gotcha.hpp" #include "library/components/pthread_gotcha.hpp" #include "library/components/ucx_gotcha.hpp" #include "library/components/vaapi_gotcha.hpp" #include "library/coverage.hpp" #include "library/process_sampler.hpp" #include "library/ptl.hpp" #include "library/rocprofiler-sdk.hpp" #include "library/runtime.hpp" #include "library/sampling.hpp" #include "library/thread_data.hpp" #include "library/thread_info.hpp" #include "library/tracing.hpp" #include "rocprofiler-systems/categories.h" // in rocprof-sys-user #include #include #include #include #include #include #include #include #include #include #include #include #include #include #if ROCPROFSYS_USE_ROCM > 0 # include # include #endif #include "logger/debug.hpp" #include #include #include #include #include #include #include #include #include #include #include #include using namespace rocprofsys; //======================================================================================// namespace rocprofsys { namespace timeout { void setup() ROCPROFSYS_INTERNAL_API; } } // namespace rocprofsys namespace { auto _timemory_manager = tim::manager::instance(); auto _timemory_settings = tim::settings::shared_instance(); void set_metadata_process_start_timestamp(int64_t _ts) { auto process_info = trace_cache::get_metadata_registry().get_process_info(); process_info.start = _ts; trace_cache::get_metadata_registry().set_process(process_info); } void set_metadata_process_end_timestamp(int64_t _ts) { auto process_info = trace_cache::get_metadata_registry().get_process_info(); process_info.end = _ts; trace_cache::get_metadata_registry().set_process(process_info); } bool ensure_initialization(bool _offset, int64_t _glob_n, int64_t _offset_n) { auto _exit_info = component::exit_gotcha::get_exit_info(); if(_exit_info.is_known && _exit_info.exit_code != EXIT_SUCCESS) return _offset; auto _tid = utility::get_thread_index(); auto _peak_num_threads = grow_data(_tid + 1); if(_tid > 0 && _tid < _peak_num_threads) { const auto& _info = thread_info::get(); LOG_DEBUG("thread info: {}, offset: {}, global counter: {}, offset counter: {}, " "max threads: {}", static_cast(_info), _offset, _glob_n, _offset_n, _peak_num_threads); } return _offset; } void finalization_handler() { if(get_state() == State::Active) rocprofsys_finalize(); } auto ensure_finalization(bool _static_init = false) { if(config::set_signal_handler(nullptr) == nullptr) config::set_signal_handler(&finalization_handler); if(_static_init) { auto _idx = threading::add_callback(&ensure_initialization); if(_idx < 0) throw exception("failure adding threading callback"); } if(config::set_signal_handler(nullptr) != &finalization_handler) throw std::runtime_error(fmt::format( "Assignment of signal handler failed. signal handler is {:P}, expected " "{:P}", fmt::format("0x{:X}", reinterpret_cast(config::set_signal_handler(nullptr))), fmt::format("0x{:X}", reinterpret_cast(&finalization_handler)))); const auto& _info = thread_info::init(); const auto& _tid = _info->index_data; if(_tid) { if(get_is_continuous_integration() && _tid->sequent_value != threading::get_id()) { throw std::runtime_error(fmt::format("Error! internal tid != {} :: {}", threading::get_id(), _tid->sequent_value)); } if(get_is_continuous_integration() && _tid->system_value != threading::get_sys_tid()) { throw std::runtime_error(fmt::format("Error! system tid != {} :: {}", threading::get_sys_tid(), _tid->system_value)); } } if(common::get_env("ROCPROFSYS_MONOCHROME", false)) tim::log::monochrome() = true; timeout::setup(); (void) tim::manager::instance(); (void) tim::settings::shared_instance(); if(!tim::get_shared_ptr_pair_callback()) { tim::get_shared_ptr_pair_callback() = new tim::shared_ptr_pair_callback_t{ [](int64_t _n) { if(_n == 0) rocprofsys_finalize_hidden(); } }; } if(_static_init) { auto _verbose = get_verbose_env() + ((get_debug_env() || get_debug_init()) ? 16 : 0); auto _search_paths = fmt::format("{}:{}:{}:{}:{}", tim::get_env("ROCPROFSYS_PATH", ""), tim::get_env("PWD"), ".", tim::get_env("LD_LIBRARY_PATH", ""), tim::get_env("LIBRARY_PATH", ""), tim::get_env("PATH", "")); common::setup_environ(_verbose, _search_paths); } if(_timemory_manager) _timemory_manager->set_write_metadata(-1); return scope::destructor{ []() { rocprofsys_finalize_hidden(); } }; } template struct fini_bundle { using data_type = std::tuple; ROCPROFSYS_DEFAULT_OBJECT(fini_bundle) fini_bundle(std::string_view _label) : m_label{ _label } {} template void start(Args&&... _args) { ROCPROFSYS_FOLD_EXPRESSION(tim::operation::start{}( std::get(m_data), std::forward(_args)...)); } template void stop(Args&&... _args) { ROCPROFSYS_FOLD_EXPRESSION(tim::operation::stop{}( std::get(m_data), std::forward(_args)...)); } std::string as_string(bool _print_prefix = true) const { std::stringstream _ss; if(_print_prefix && m_label.length() > 0) _ss << m_label << " : "; size_t _idx = 0; ((_ss << (_idx++ > 0 ? ", " : "") << std::get(m_data)), ...); return _ss.str(); } std::string_view m_label = {}; data_type m_data = {}; }; template struct fini_bundle> { using base_type = fini_bundle; }; using fini_bundle_t = typename fini_bundle::base_type; } // namespace //======================================================================================// /// /// /// //======================================================================================// namespace { struct set_env_s // NOLINT {}; } // namespace extern "C" void rocprofsys_set_env_hidden(const char* env_name, const char* env_val) { tim::auto_lock_t _lk{ tim::type_mutex() }; static auto _set_envs = std::set{}; bool _success = _set_envs.emplace(env_name).second; // just search env to avoid initializing the settings if(get_debug_init()) { LOG_DEBUG("Setting env: {} = {}", env_name, env_val); } tim::set_env(env_name, env_val, 0); if(_success && get_state() >= State::Init) { LOG_WARNING( "rocprofsys_set_env(\"{}\", \"{}\") called after rocprof-sys was " "initialized. state = {}. This environment variable will have no effect", env_name, env_val, static_cast(get_state())); } } //======================================================================================// /// /// /// //======================================================================================// namespace { bool _set_mpi_called = false; std::function _preinit_callback = []() { get_preinit_bundle()->start(); }; std::vector read_command_line(pid_t _pid) { auto _cmdline = std::vector{}; auto fcmdline = std::stringstream{}; fcmdline << "/proc/" << _pid << "/cmdline"; auto ifs = std::ifstream{ fcmdline.str().c_str() }; if(ifs) { std::string sarg; while(std::getline(ifs, sarg, '\0')) { _cmdline.push_back(sarg); } ifs.close(); } return _cmdline; } void rocprofsys_preinit_cache() { auto _cmd_line = read_command_line(getpid()); if(_cmd_line.empty()) { _cmd_line.emplace_back("rocprofiler-systems"); } trace_cache::get_metadata_registry().set_process( { getpid(), getppid(), _cmd_line.at(0) }); } void rocprofsys_preinit_cpu_agents() { cpu::query_cpu_agents(); } void rocprofsys_preinit_hidden() { // run once and discard _preinit_callback(); _preinit_callback = []() {}; } } // namespace extern "C" void rocprofsys_set_mpi_hidden(bool use, bool attached) { static bool _once = false; static auto _args = std::make_pair(use, attached); // this function may be called multiple times if multiple libraries are instrumented // we want to guard against multiple calls which with different arguments if(_once && std::tie(_args.first, _args.second) == std::tie(use, attached)) return; _once = true; // just search env to avoid initializing the settings if(get_debug_init()) { LOG_DEBUG("use: {}, attached: {}", (use) ? "y" : "n", (attached) ? "y" : "n"); } _set_mpi_called = true; config::is_attached() = attached; if(use && !attached && get_state() == State::PreInit) { tim::set_env("ROCPROFSYS_USE_PID", "ON", 1); } else if(!use) { trait::runtime_enabled::set(false); } if(get_state() >= State::Init) { LOG_WARNING( "rocprofsys_set_mpi(use={}, attached={}) called after rocprof-sys was " "initialized. state = {}. MPI support may not be properly initialized. Use " "ROCPROFSYS_USE_MPIP=ON and ROCPROFSYS_USE_PID=ON to ensure full support", use, attached, static_cast(get_state())); } rocprofsys_preinit_hidden(); } //======================================================================================// extern "C" void rocprofsys_init_library_hidden() { auto _tid = threading::get_id(); (void) _tid; static bool _once = false; auto _debug_init = get_debug_init(); int _selinux_mode = 0; { std::ifstream _fenforcing{ "/sys/fs/selinux/enforce" }; if(!(_fenforcing >> _selinux_mode)) _selinux_mode = 0; _fenforcing.close(); } if(_selinux_mode == 1) { LOG_DEBUG("/sys/fs/selinux/enforce has a value of {}.", _selinux_mode); LOG_CRITICAL("SELinux enforcing mode detected. Consider disabling SELinux " "or configure permissive mode with 'sudo setenforce 0'. Aborting."); std::exit(EXIT_FAILURE); } if(_debug_init) { LOG_DEBUG("State is {}...", std::to_string(get_state())); } if(get_is_continuous_integration() && get_state() != State::PreInit) { throw std::runtime_error( fmt::format("State is not PreInit :: {}", std::to_string(get_state()))); } if(get_state() != State::PreInit || get_state() == State::Init || _once) return; _once = true; ROCPROFSYS_SCOPED_THREAD_STATE(ThreadState::Internal); if(_debug_init) { LOG_DEBUG("State is {}. Setting to {}...", std::to_string(get_state()), std::to_string(State::Init)); LOG_DEBUG("Calling backtrace once so that the one-time call of malloc in " "glibc's backtrace() occurs..."); } { std::stringstream _ss{}; timemory_print_backtrace<16>(_ss); (void) _ss; } set_state(State::Init); if(get_is_continuous_integration() && get_state() != State::Init) { throw std::runtime_error(fmt::format("set_state(State::Init) failed. state is {}", std::to_string(get_state()))); } if(_debug_init) { LOG_DEBUG("Configuring settings..."); } // configure the settings configure_settings(); auto _debug_value = get_debug(); if(_debug_init) config::set_setting_value("ROCPROFSYS_DEBUG", true); scope::destructor _debug_dtor{ [_debug_value, _debug_init]() { if(_debug_init) config::set_setting_value("ROCPROFSYS_DEBUG", _debug_value); } }; } //======================================================================================// extern "C" bool rocprofsys_init_tooling_hidden(void) { if(get_env("ROCPROFSYS_MONOCHROME", false, false)) tim::log::monochrome() = true; if(!tim::get_env("ROCPROFSYS_INIT_TOOLING", true)) { rocprofsys_init_library_hidden(); return false; } #if ROCPROFSYS_USE_ROCM > 0 dynamic_library _amdhip64{ "ROCPROFSYS_ROCTRACER_LIBAMDHIP64", find_library_path("libamdhip64.so", { "ROCPROFSYS_ROCM_PATH", "ROCM_PATH" }, { ROCPROFSYS_DEFAULT_ROCM_PATH }) }; #endif static pid_t _once = 0; static auto _debug_init = get_debug_init(); if(_debug_init) { LOG_DEBUG("State is {}...", std::to_string(get_state())); } if(get_state() != State::PreInit || get_state() == State::Init || _once == getpid()) { return false; } _once = getpid(); ROCPROFSYS_SCOPED_THREAD_STATE(ThreadState::Internal); if(get_state() == State::Init) { throw std::runtime_error( fmt::format("{} called after rocprofsys_init_library() was explicitly called", __FUNCTION__)); } LOG_DEBUG("Instrumentation mode: {}", std::to_string(config::get_mode())); if(_debug_init) { LOG_DEBUG("Printing banner..."); } print_banner(); if(_debug_init) { LOG_DEBUG("Calling rocprofsys_init_library()..."); } rocprofsys_init_library_hidden(); auto _dtor = scope::destructor{ []() { // if set to finalized, don't continue if(get_state() > State::Active) return; #if !(ROCPROFSYS_USE_ROCM > 0) rocprofsys_preinit_cpu_agents(); #endif rocprofsys_preinit_cache(); if(get_use_process_sampling()) { ROCPROFSYS_SCOPED_SAMPLING_ON_CHILD_THREADS(false); process_sampler::setup(); } if(get_use_causal()) { { ROCPROFSYS_SCOPED_SAMPLING_ON_CHILD_THREADS(false); causal::sampling::setup(); } push_enable_sampling_on_child_threads(get_use_causal()); sampling::unblock_signals(); } else if(get_use_sampling()) { { ROCPROFSYS_SCOPED_SAMPLING_ON_CHILD_THREADS(false); sampling::setup(); } push_enable_sampling_on_child_threads(get_use_sampling()); sampling::unblock_signals(); } get_main_bundle()->start(); LOG_DEBUG("State: {} -> State::Active", std::to_string(get_state())); { ROCPROFSYS_SCOPED_SAMPLING_ON_CHILD_THREADS(false); trace_cache::get_buffer_storage().start(getpid()); } set_state(State::Active); // set to active as very last operation } }; ROCPROFSYS_SCOPED_SAMPLING_ON_CHILD_THREADS(false); // ideally these have already been started rocprofsys_preinit_hidden(); // start these gotchas once settings have been initialized if(get_init_bundle()) get_init_bundle()->start(); if(get_use_ucx()) { LOG_DEBUG("Setting up UCX traces...\n"); component::ucx_gotcha::start(); } if(get_use_vaapi_tracing()) { LOG_DEBUG("Setting up VA-API traces..."); component::vaapi_gotcha::start(); } if(get_use_sampling()) sampling::block_signals(); // perfetto initialization if(get_use_perfetto()) { LOG_DEBUG("Setting up Perfetto..."); rocprofsys::perfetto::setup(); } tasking::setup(); if(get_use_causal()) causal::start_experimenting(); if(get_use_timemory()) { comp::user_global_bundle::global_init(); std::set _comps{}; // convert string into set of enumerations for(auto&& itr : tim::delimit(tim::settings::global_components())) _comps.emplace(tim::runtime::enumerate(itr)); if(_comps.size() == 1 && _comps.find(TIMEMORY_WALL_CLOCK) != _comps.end()) { // using wall_clock directly is lower overhead than using it via user_bundle instrumentation_bundle_t::get_initializer() = [](instrumentation_bundle_t& _bundle) { _bundle.initialize(); }; } else if(!_comps.empty()) { // use user_bundle for other than wall-clock instrumentation_bundle_t::get_initializer() = [](instrumentation_bundle_t& _bundle) { _bundle.initialize(); }; } else { tim::trait::runtime_enabled::set(false); } } if(get_use_perfetto()) { LOG_DEBUG("Starting Perfetto..."); rocprofsys::perfetto::start(); } categories::setup(); // if static objects are destroyed in the inverse order of when they are // created this should ensure that finalization is called before perfetto // ends the tracing session static auto _ensure_finalization = ensure_finalization(); return true; } //======================================================================================// extern "C" void rocprofsys_init_hidden(const char* _mode, bool _is_binary_rewrite, const char* _argv0_c) { static int _total_count = 0; static auto _args = std::make_pair(std::string_view{ _mode }, _is_binary_rewrite); auto _count = _total_count++; auto _mode_sv = std::string_view{ _mode }; auto _argv0 = (_argv0_c) ? std::string{ _argv0_c } : config::get_exe_name(); // this function may be called multiple times if multiple libraries are instrumented // we want to guard against multiple calls which with different arguments if(_count > 0 && std::tie(_args.first, _args.second) == std::tie(_mode_sv, _is_binary_rewrite)) return; if(_count > 0 && std::tie(_args.first, _args.second) != std::tie(_mode_sv, _is_binary_rewrite)) { throw std::runtime_error(fmt::format( "rocprofsys_init(...) called multiple times with different arguments for " "mode and/or is_binary_rewrite:" "\n Invocation #1: rocprofsys_init(mode={}, is_binary_rewrite={}, ...)" "\n Invocation #%i: rocprofsys_init(mode={}, is_binary_rewrite={}, ...)", _args.first.data(), std::to_string(_args.second).c_str(), _count + 1, _mode, std::to_string(_is_binary_rewrite))); } // always the first (void) get_state(); (void) tracing::push_count(); (void) tracing::pop_count(); if(get_state() >= State::Init) { if(std::string_view{ _mode } != "trace" && std::string_view{ _mode } != "Trace") { LOG_WARNING( "rocprofsys_init(mode={}, is_binary_rewrite={}, argv0={}) " "called after rocprof-sys was initialized. state = {}. Mode-based " "settings (via -M passed to rocprof-sys exe) may not be " "properly configured.", _mode, std::to_string(_is_binary_rewrite), _argv0, std::to_string(get_state())); } } tracing::get_finalization_functions().emplace_back([_argv0_c]() { if(get_is_continuous_integration() && get_state() != State::Active) { throw std::runtime_error( fmt::format("Finalizer function for popping main invoked in non-active " "state :: state = {}", std::to_string(get_state()))); } if(get_state() == State::Active) { auto _name = (_argv0_c) ? std::string{ _argv0_c } : config::get_exe_name(); // if main hasn't been popped yet, pop it LOG_DEBUG("Running rocprofsys_pop_trace({})...", _name); rocprofsys_pop_trace_hidden(_name.c_str()); } }); std::atexit([]() { // if active (not already finalized) then we should finalize if(get_state() == State::Active) rocprofsys_finalize_hidden(); }); set_metadata_process_start_timestamp(comp::wall_clock::record()); if(get_debug_env() || get_verbose_env() > 2) { LOG_DEBUG("mode: {} | is binary rewrite: {} | command: {}", _mode, (_is_binary_rewrite) ? "y" : "n", _argv0); } tim::set_env("ROCPROFSYS_MODE", _mode, 0); config::is_binary_rewrite() = _is_binary_rewrite; if(_set_mpi_called) { rocprofsys_preinit_hidden(); } } //======================================================================================// extern "C" void rocprofsys_reset_preload_hidden(void) { tim::set_env("ROCPROFSYS_PRELOAD", "0", 1); auto&& _preload_libs = common::get_env("LD_PRELOAD", std::string{}); if(_preload_libs.find("librocprof-sys") != std::string::npos) { auto _modified_preload = std::string{}; for(const auto& itr : delimit(_preload_libs, ":")) { if(itr.find("librocprof-sys") != std::string::npos) continue; _modified_preload += fmt::format(":{}", itr); } if(!_modified_preload.empty() && _modified_preload.find(':') == 0) _modified_preload = _modified_preload.substr(1); tim::set_env("LD_PRELOAD", _modified_preload, 1); } } //======================================================================================// extern "C" void rocprofsys_finalize_hidden(void) { // disable thread id recycling during finalization threading::recycle_ids() = false; // disable initialization callback threading::remove_callback(&ensure_initialization); bool _is_child = is_child_process(); set_thread_state(ThreadState::Completed); // return if not active if(get_state() != State::Active) { LOG_DEBUG("State = {}. Finalization skipped", std::to_string(get_state())); return; } set_metadata_process_end_timestamp(comp::wall_clock::record()); if(_is_child) { set_state(State::Finalized); #if defined(ROCPROFSYS_USE_ROCM) && ROCPROFSYS_USE_ROCM > 0 // Flush buffered traces in case of child process if(get_use_rocm()) { LOG_DEBUG("Shutting down ROCm..."); rocprofiler_sdk::shutdown(); } #endif auto& _manager = rocprofsys::trace_cache::cache_manager::get_instance(); const auto _agents = get_agent_manager_instance().get_agents(); _manager.shutdown(); const auto metadata_filepath = trace_cache::utility::get_metadata_filepath(get_root_process_id(), getpid()); _manager.get_metadata_registry().save_to_file(metadata_filepath, _agents); std::quick_exit(EXIT_SUCCESS); return; } LOG_INFO("Finalizing rocprof-sys..."); sampling::block_samples(); thread_info::set_stop(comp::wall_clock::record()); tim::signals::block_signals(get_sampling_signals(), tim::signals::sigmask_scope::process); rocprofsys_reset_preload_hidden(); // some functions called during finalization may alter the push/pop count so we need // to save them here auto _push_count = tracing::push_count().load(); auto _pop_count = tracing::pop_count().load(); // e.g. rocprofsys_pop_trace("main"); if(_push_count > _pop_count) { for(auto& itr : tracing::get_finalization_functions()) { itr(); ++_pop_count; } } set_state(State::Finalized); push_enable_sampling_on_child_threads(false); set_sampling_on_all_future_threads(false); // if the categories are not enabled, it can/will suppress generating output for data // in category categories::enable_categories(); auto _debug_init = get_debug_finalize(); auto _debug_value = get_debug(); if(_debug_init) config::set_setting_value("ROCPROFSYS_DEBUG", true); scope::destructor _debug_dtor{ [_debug_value, _debug_init]() { if(_debug_init) config::set_setting_value("ROCPROFSYS_DEBUG", _debug_value); } }; auto& _thread_bundle = thread_data::instance(); if(_thread_bundle) _thread_bundle->stop(); if(get_verbose() >= 1 || get_debug()) { if(dmp::rank() == 0) { config::print_settings( tim::get_env("ROCPROFSYS_PRINT_ENV", get_debug())); } } LOG_DEBUG("rocprofsys_push_trace :: called {}", _push_count); LOG_DEBUG("rocprofsys_pop_trace :: called {}", _pop_count); tim::signals::enable_signal_detection({ tim::signals::sys_signal::Interrupt }, [](int) {}); LOG_DEBUG("Copying over all timemory hash information to main thread..."); tracing::copy_timemory_hash_ids(); // stop the main bundle which has stats for run if(get_main_bundle()) { LOG_DEBUG("Stopping main bundle..."); get_main_bundle()->stop(); } fini_bundle_t _finalization{}; _finalization.start(); if(get_use_ucx()) { LOG_DEBUG("Shutting down UCX tracing...\n"); component::ucx_gotcha::shutdown(); } if(get_use_vaapi_tracing()) { LOG_DEBUG("Shutting down VA-API tracing..."); component::vaapi_gotcha::shutdown(); } #if defined(ROCPROFSYS_USE_ROCM) && ROCPROFSYS_USE_ROCM > 0 if(get_use_rocm()) { LOG_DEBUG("Shutting down ROCm..."); rocprofiler_sdk::shutdown(); } #endif LOG_DEBUG("Stopping and destroying instrumentation bundles..."); auto* _bundles = instrumentation_bundles::get(); for(size_t i = 0; _bundles && i < thread_info::get_peak_num_threads(); ++i) { if(i >= _bundles->size()) continue; const auto& _info = thread_info::get(i, SequentTID); auto& itr = _bundles->at(i); while(itr != nullptr && !itr->empty()) { if(_info->is_offset) { ++_pop_count; } LOG_WARNING("Instrumentation bundle on thread {} (TID={}) " "with label '{}' was not stopped.", i, itr->back()->tid(), itr->back()->key()); itr->back()->stop(); itr->back()->pop(); itr->pop_back(); } } // stop the main gotcha which shuts down the pthread gotchas if(get_init_bundle()) { LOG_DEBUG("Stopping main gotcha..."); get_init_bundle()->stop(); pthread_gotcha::shutdown(); component::numa_gotcha::shutdown(); } // stop the gotcha bundle if(get_preinit_bundle()) { LOG_DEBUG("Shutting down miscellaneous gotchas..."); get_preinit_bundle()->stop(); component::mpi_gotcha::shutdown(); } if(get_use_process_sampling()) { LOG_DEBUG("Shutting down background sampler..."); process_sampler::shutdown(); } if(get_use_causal()) { LOG_DEBUG("Shutting down causal sampling..."); causal::sampling::shutdown(); } if(get_use_sampling()) { LOG_DEBUG("Shutting down sampling..."); sampling::shutdown(); } LOG_TRACE("Reporting the process- and thread-level metrics..."); // report the high-level metrics for the process if(get_main_bundle()) { std::string _msg = get_main_bundle()->as_string(); auto _pos = _msg.find(">>> "); if(_pos != std::string::npos) _msg = _msg.substr(_pos + 5); LOG_INFO("{}", _msg); LOG_DEBUG("Resetting main bundle..."); get_main_bundle()->reset(); } // print out thread-data if they are not still running // if they are still running (e.g. thread-pool still alive), the // thread-specific data will be wrong if try to stop them from // the main thread. auto _thr_verbose = (config::get_use_causal()) ? 1 : 0; if(thread_data::get()) { for(auto& itr : *thread_data::get()) { if(itr && itr->get() && !itr->get()->get_is_running()) { std::string _msg = itr->as_string(); auto _pos = _msg.find(">>> "); if(_pos != std::string::npos) _msg = _msg.substr(_pos + 5); if(_thr_verbose >= 0) { LOG_INFO("{}", _msg); } } } } // ensure that all the MT instances are flushed if(get_use_sampling()) { LOG_DEBUG("Post-processing the sampling backtraces..."); sampling::post_process(); } if(get_use_causal()) { LOG_DEBUG("Finishing the causal experiments..."); causal::finish_experimenting(); } if(get_use_process_sampling()) { LOG_DEBUG("Post-processing the system-level samples..."); process_sampler::post_process(); } // shutdown tasking before timemory is finalized LOG_DEBUG("Shutting down thread-pools..."); tasking::shutdown(); if(get_use_code_coverage()) { LOG_DEBUG("Post-processing the code coverage..."); coverage::post_process(); } tracing::copy_timemory_hash_ids(); // Flush any pending region cache entries (e.g., main entry point that wasn't // explicitly stopped before finalization) LOG_DEBUG("Flushing pending region cache entries..."); rocprofsys_flush_pending_region_cache_hidden(); bool _perfetto_output_error = false; if(get_use_perfetto()) { LOG_DEBUG("Finalizing perfetto..."); rocprofsys::perfetto::post_process(_timemory_manager.get(), _perfetto_output_error); } { auto& _manager = rocprofsys::trace_cache::cache_manager::get_instance(); _manager.shutdown(); _manager.post_process_bulk(); } if(_timemory_manager && _timemory_manager != nullptr) { _timemory_manager->add_metadata([](auto& ar) { auto _maps = tim::procfs::read_maps(process::get_id()); auto _libs = std::set{}; for(auto& itr : _maps) { auto&& _path = itr.pathname; if(!_path.empty() && _path.at(0) != '[' && filepath::exists(_path)) _libs.emplace(_path); } ar(tim::cereal::make_nvp("memory_maps_files", _libs), tim::cereal::make_nvp("memory_maps", _maps)); }); LOG_DEBUG("Finalizing timemory..."); tim::timemory_finalize(_timemory_manager.get()); auto _cfg = settings::compose_filename_config{}; _cfg.use_suffix = config::get_use_pid(); _cfg.suffix = settings::default_process_suffix(); _timemory_manager->write_metadata(settings::get_global_output_prefix(), "rocprofsys", _cfg); } categories::shutdown(); _finalization.stop(); if(_perfetto_output_error) { throw std::runtime_error(fmt::format("Error opening perfetto output file: {}", get_perfetto_output_filename())); } if(get_is_continuous_integration() && _push_count > _pop_count && !get_env("ROCPROFSYS_CI_SKIP_PUSH_POP_CHECK", false, false)) { throw std::runtime_error(fmt::format( "rocprofsys_push_trace was called more times than " "rocprofsys_pop_trace. The inverse is fine but the current state " "means not every measurement was ended :: pushed: {} vs. popped: {}", _push_count, _pop_count)); } // debug::close_file(); config::finalize(); LOG_DEBUG("Finalized: {}", _finalization.as_string()); tim::signals::enable_signal_detection( { tim::signals::sys_signal::SegFault, tim::signals::sys_signal::Stop }, [](int) {}); common::destroy_static_objects(); } //======================================================================================// namespace { // if static objects are destroyed randomly (relatively uncommon behavior) // this might call finalization before perfetto ends the tracing session // but static variable in rocprofsys_init_tooling_hidden is more likely auto _ensure_finalization = ensure_finalization(true); auto _manager = tim::manager::instance(); auto _settings = tim::settings::shared_instance(); } // namespace