// MIT License // // Copyright (c) 2023 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. // // undefine NDEBUG so asserts are implemented #ifdef NDEBUG # undef NDEBUG #endif /** * @file samples/advanced_thread_trace/client.cpp * * @brief Example rocprofiler client (tool) */ #include #include #include #include #include #include #include #include #include "common/defines.hpp" #include "common/filesystem.hpp" #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #define OUTPUT_OFSTREAM "advanced_thread_trace.log" #define TARGET_CU 1 #define SIMD_SELECT 0x3 #define BUFFER_SIZE 0x6000000 #define SE_MASK 0x11 constexpr bool COPY_MEMORY_CODEOBJ = true; #define C_API_BEGIN \ try \ { #define C_API_END \ } \ catch(std::exception & e) \ { \ std::cerr << "Error in " << __FILE__ << ':' << __LINE__ << ' ' << e.what() << std::endl; \ } \ catch(...) { std::cerr << "Error in " << __FILE__ << ':' << __LINE__ << std::endl; } struct pcinfo_t { uint64_t marker_id; uint64_t addr; }; bool operator==(const pcinfo_t& a, const pcinfo_t& b) { return a.addr == b.addr && a.marker_id == b.marker_id; }; bool operator<(const pcinfo_t& a, const pcinfo_t& b) { if(a.marker_id == b.marker_id) return a.addr < b.addr; return a.marker_id < b.marker_id; }; namespace client { using code_obj_load_data_t = rocprofiler_callback_tracing_code_object_load_data_t; using kernel_symbol_data_t = rocprofiler_callback_tracing_code_object_kernel_symbol_register_data_t; using Instruction = rocprofiler::codeobj::disassembly::Instruction; using CodeobjAddressTranslate = rocprofiler::codeobj::disassembly::CodeobjAddressTranslate; using SymbolInfo = rocprofiler::codeobj::disassembly::SymbolInfo; rocprofiler_client_id_t* client_id = nullptr; rocprofiler_context_id_t client_ctx = {}; struct isa_map_elem_t { std::atomic hitcount{0}; std::atomic latency{0}; std::unique_ptr code_line{nullptr}; }; struct ToolData { ToolData() { try { output_file.open(OUTPUT_OFSTREAM); } catch(...) {} if(output_file.is_open()) std::cout << "Writing code-object-isa-decode log to: " << OUTPUT_OFSTREAM << std::endl; else std::cout << "Could not open log file: " << OUTPUT_OFSTREAM << ", writing to stdout\n"; }; std::shared_mutex isa_map_mut; std::mutex output_mut; CodeobjAddressTranslate codeobjTranslate; std::map> isa_map; std::unordered_map kernels_in_codeobj = {}; std::unordered_map kernel_id_to_kernel_name = {}; int num_waves = 0; std::ostream& output() { if(output_file.is_open()) return output_file; else return std::cout; } std::stringstream printKernel(uint64_t vaddr) { std::stringstream ss; try { ss << '\n' << std::hex; SymbolInfo& info = kernels_in_codeobj.at(vaddr); ss << std::hex << "Found: " << info.name << " at addr: 0x" << vaddr << " with offset 0x" << info.faddr << " vaddr 0x" << info.vaddr << std::dec << '\n'; } catch(std::exception& e) { ss << e.what() << '\n'; } return ss; } private: std::ofstream output_file; }; struct source_location { std::string function = {}; std::string file = {}; uint32_t line = 0; std::string context = {}; }; struct trace_data_t { int64_t id; uint8_t* data; uint64_t size; }; auto* tool = new ToolData{}; void tool_codeobj_tracing_callback(rocprofiler_callback_tracing_record_t record, rocprofiler_user_data_t* /* user_data */, void* /* callback_data */) { C_API_BEGIN if(record.kind != ROCPROFILER_CALLBACK_TRACING_CODE_OBJECT) return; if(record.phase != ROCPROFILER_CALLBACK_PHASE_LOAD) return; if(record.operation == ROCPROFILER_CODE_OBJECT_DEVICE_KERNEL_SYMBOL_REGISTER) { std::unique_lock lg(tool->isa_map_mut); auto* data = static_cast(record.payload); tool->kernel_id_to_kernel_name.emplace(data->kernel_id, data->kernel_name); } if(record.operation != ROCPROFILER_CODE_OBJECT_LOAD) return; std::unique_lock lg(tool->isa_map_mut); auto* data = static_cast(record.payload); if(std::string_view(data->uri).find("file:///") == 0) { tool->codeobjTranslate.addDecoder( data->uri, data->code_object_id, data->load_delta, data->load_size); auto symbolmap = tool->codeobjTranslate.getSymbolMap(data->code_object_id); for(auto& [vaddr, symbol] : symbolmap) tool->kernels_in_codeobj[vaddr] = symbol; } else if(COPY_MEMORY_CODEOBJ) { tool->codeobjTranslate.addDecoder(reinterpret_cast(data->memory_base), data->memory_size, data->code_object_id, data->load_delta, data->load_size); auto symbolmap = tool->codeobjTranslate.getSymbolMap(data->code_object_id); for(auto& [vaddr, symbol] : symbolmap) tool->kernels_in_codeobj[vaddr] = symbol; } C_API_END } rocprofiler_att_control_flags_t dispatch_callback(rocprofiler_queue_id_t /* queue_id */, const rocprofiler_agent_t* /* agent */, rocprofiler_correlation_id_t /* correlation_id */, rocprofiler_kernel_id_t kernel_id, rocprofiler_dispatch_id_t /* dispatch_id */, rocprofiler_user_data_t* /* userdata */, void* /* userdata */) { C_API_BEGIN std::shared_lock lg(tool->isa_map_mut); static std::atomic call_id{0}; static std::string_view desired_func_name = "transposeLds"; try { auto& kernel_name = tool->kernel_id_to_kernel_name.at(kernel_id); if(kernel_name.find(desired_func_name) == std::string::npos) return ROCPROFILER_ATT_CONTROL_NONE; int id = call_id.fetch_add(1); if(id == 1) return ROCPROFILER_ATT_CONTROL_START_AND_STOP; } catch(...) { std::cerr << "Could not find kernel id: " << kernel_id << std::endl; } C_API_END return ROCPROFILER_ATT_CONTROL_NONE; } void get_trace_data(rocprofiler_att_parser_data_type_t type, void* att_data, void* userdata) { C_API_BEGIN assert(userdata && "ISA callback passed null!"); std::shared_lock shared_lock(tool->isa_map_mut); if(type == ROCPROFILER_ATT_PARSER_DATA_TYPE_OCCUPANCY) tool->num_waves++; if(type != ROCPROFILER_ATT_PARSER_DATA_TYPE_ISA) return; auto& event = *reinterpret_cast(att_data); pcinfo_t pc{event.marker_id, event.offset}; auto it = tool->isa_map.find(pc); if(it == tool->isa_map.end()) { shared_lock.unlock(); { std::unique_lock unique_lock(tool->isa_map_mut); auto ptr = std::make_unique(); try { ptr->code_line = tool->codeobjTranslate.get(pc.marker_id, pc.addr); } catch(std::exception& e) { std::cerr << pc.marker_id << ":" << pc.addr << ' ' << e.what() << std::endl; return; } catch(...) { std::cerr << "Could not fetch: " << pc.marker_id << ':' << pc.addr << std::endl; return; } it = tool->isa_map.emplace(pc, std::move(ptr)).first; } shared_lock.lock(); } it->second->hitcount.fetch_add(event.hitcount, std::memory_order_relaxed); it->second->latency.fetch_add(event.latency, std::memory_order_relaxed); C_API_END } uint64_t copy_trace_data(int* seid, uint8_t** buffer, uint64_t* buffer_size, void* userdata) { trace_data_t& data = *reinterpret_cast(userdata); *seid = data.id; *buffer_size = data.size; *buffer = data.data; data.size = 0; return *buffer_size; } rocprofiler_status_t isa_callback(char* isa_instruction, uint64_t* isa_memory_size, uint64_t* isa_size, uint64_t marker_id, uint64_t offset, void* userdata) { C_API_BEGIN assert(userdata && "ISA callback passed null!"); std::unique_ptr instruction; { std::unique_lock unique_lock(tool->isa_map_mut); instruction = tool->codeobjTranslate.get(marker_id, offset); } if(!instruction.get()) return ROCPROFILER_STATUS_ERROR_INVALID_ARGUMENT; { size_t tmp_isa_size = *isa_size; *isa_size = instruction->inst.size(); if(*isa_size > tmp_isa_size) return ROCPROFILER_STATUS_ERROR_OUT_OF_RESOURCES; } memcpy(isa_instruction, instruction->inst.data(), *isa_size); *isa_memory_size = instruction->size; auto ptr = std::make_unique(); ptr->code_line = std::move(instruction); tool->isa_map.emplace(pcinfo_t{marker_id, offset}, std::move(ptr)); return ROCPROFILER_STATUS_SUCCESS; C_API_END return ROCPROFILER_STATUS_ERROR; } void shader_data_callback(int64_t se_id, void* se_data, size_t data_size, rocprofiler_user_data_t /* userdata */) { C_API_BEGIN { std::unique_lock lk(tool->output_mut); tool->output() << "SE ID: " << se_id << " with size " << data_size << std::hex << '\n'; } trace_data_t data{.id = se_id, .data = (uint8_t*) se_data, .size = data_size}; auto status = rocprofiler_att_parse_data(copy_trace_data, get_trace_data, isa_callback, &data); if(status != ROCPROFILER_STATUS_SUCCESS) std::cerr << "shader_data_callback failed with status " << status << std::endl; C_API_END } int tool_init(rocprofiler_client_finalize_t fini_func, void* tool_data) { (void) fini_func; ROCPROFILER_CALL(rocprofiler_create_context(&client_ctx), "context creation"); ROCPROFILER_CALL( rocprofiler_configure_callback_tracing_service(client_ctx, ROCPROFILER_CALLBACK_TRACING_CODE_OBJECT, nullptr, 0, tool_codeobj_tracing_callback, tool_data), "code object tracing service configure"); std::vector parameters = { {ROCPROFILER_ATT_PARAMETER_TARGET_CU, {TARGET_CU}}, {ROCPROFILER_ATT_PARAMETER_SIMD_SELECT, {SIMD_SELECT}}, {ROCPROFILER_ATT_PARAMETER_BUFFER_SIZE, {BUFFER_SIZE}}, {ROCPROFILER_ATT_PARAMETER_SHADER_ENGINE_MASK, {SE_MASK}}}; ROCPROFILER_CALL(rocprofiler_configure_dispatch_thread_trace_service(client_ctx, parameters.data(), parameters.size(), dispatch_callback, shader_data_callback, tool_data), "thread trace service configure"); int valid_ctx = 0; ROCPROFILER_CALL(rocprofiler_context_is_valid(client_ctx, &valid_ctx), "context validity check"); if(valid_ctx == 0) { // notify rocprofiler that initialization failed // and all the contexts, buffers, etc. created // should be ignored return -1; } ROCPROFILER_CALL(rocprofiler_start_context(client_ctx), "context start"); // no errors return 0; } void tool_fini(void* /* data */) { std::unique_lock isa_lk(client::tool->isa_map_mut); std::unique_lock out_lk(client::tool->output_mut); // Find largest instruction size_t max_inst_size = 0; for(auto& [addr, lines] : client::tool->isa_map) if(lines.get()) max_inst_size = std::max(max_inst_size, lines->code_line->inst.size()); std::string empty_space; empty_space.resize(max_inst_size, ' '); size_t vmc_latency = 0; size_t lgk_latency = 0; size_t scalar_latency = 0; size_t vector_latency = 0; size_t other_latency = 0; size_t scalar_exec = 0; size_t vector_exec = 0; size_t other_exec = 0; for(auto& [addr, line] : client::tool->isa_map) if(line.get()) { size_t hitcount = line->hitcount.load(std::memory_order_relaxed); size_t latency = line->latency.load(std::memory_order_relaxed); auto& code_line = line->code_line->inst; client::tool->output() << std::hex << "0x" << addr.addr << std::dec << ' ' << code_line << empty_space.substr(0, max_inst_size - code_line.size()) << " Hit: " << hitcount << " - Latency: " << latency << '\n'; if(code_line.find("s_waitcnt") == 0) { other_exec += hitcount; if(code_line.find("lgkmcnt") != std::string::npos) lgk_latency += latency; else vmc_latency += latency; } else if(code_line.find("v_") == 0) { vector_exec += hitcount; vector_latency += latency; } else if(code_line.find("s_") == 0) { scalar_exec += hitcount; scalar_latency += latency; } else { other_exec += hitcount; other_latency += latency; } } size_t total_exec = vector_exec + scalar_exec + other_exec; size_t memory_latency = vmc_latency + lgk_latency; size_t total_latency = memory_latency + vector_latency + scalar_latency + other_latency; float vmc_fraction = 100 * vmc_latency / float(total_latency); float lgk_fraction = 100 * lgk_latency / float(total_latency); client::tool->output() << "Total executed instructions: " << total_exec << '\n' << "Total executed vector instructions: " << vector_exec << " with average " << vector_latency / float(vector_exec) << " cycles.\n" << "Total executed scalar instructions: " << scalar_exec << " with average " << scalar_latency / float(scalar_exec) << " cycles.\n" << "Vector memory ops occupied: " << vmc_fraction << "% of cycles.\n" << "Scalar and LDS memory ops occupied: " << lgk_fraction << "% of cycles.\n" << "Num waves created: " << (client::tool->num_waves / 2) << std::endl; } } // namespace client extern "C" rocprofiler_tool_configure_result_t* rocprofiler_configure(uint32_t version, const char* runtime_version, uint32_t priority, rocprofiler_client_id_t* id) { // set the client name id->name = "Adv_Thread_Trace_Sample"; // store client info client::client_id = id; // compute major/minor/patch version info uint32_t major = version / 10000; uint32_t minor = (version % 10000) / 100; uint32_t patch = version % 100; // generate info string auto info = std::stringstream{}; info << id->name << " (priority=" << priority << ") is using rocprofiler-sdk v" << major << "." << minor << "." << patch << " (" << runtime_version << ")"; std::clog << info.str() << std::endl; // create configure data static auto cfg = rocprofiler_tool_configure_result_t{sizeof(rocprofiler_tool_configure_result_t), &client::tool_init, &client::tool_fini, nullptr}; // return pointer to configure data return &cfg; }