Rocprofiler-Compute version: 3.7.0
Profiler choice: rocprofiler-sdk
Output directory: /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/tests/workloads/vcopy/RDNA35_HALO
Target: RDNA35_HALO
Command: ./tests/vcopy -n 1048576 -b 256 -i 3
Kernel Selection: None
Dispatch Selection: None
Filtered sections: All

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Collecting Performance Counters
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

Building native tool using command: cmake -S /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib -B /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib/_build
-- HIP_PLATFORM using HIPCONFIG_EXEC: amd
-- {fmt} version: 12.1.0
-- Build type:
-- Configuring done (0.0s)
-- Generating done (0.0s)
-- Build files have been written to: /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib/_build
Generating native tool project using command: cmake --build /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib/_build --parallel
[  0%] Built target gsl_assert
[ 33%] Built target fmt
[100%] Built target rocprofiler-compute-tool
Searching /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src by lib/_build/lib/librocprofiler-compute-tool.so for native collector
Using native collector: /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib/_build/lib/librocprofiler-compute-tool.so
Using native counter collection tool: /home/rocprof/xuchen/rocm-systems/projects/rocprofiler-compute/src/lib/_build/lib/librocprofiler-compute-tool.so
[profiling] Iteration multiplexing: Disabled
[Run 1/10][Approximate profiling time left: pending first measurement...]
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_0.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:04.463482 3531398 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.072678 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:04.489064 3531398 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000001 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.509693 3531398 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:04.573269 3531398 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.084204 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.588262 3531398 generateRocpd.cpp:1042] writing SQL database for process 3531398 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:04.588668 3531398 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531398_results.db (UUID=000091af-8fbb-7fbb-9275-1f23852c575a)
   |-> [rocprofiler-sdk] W0506 23:17:04.660878 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.002928 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.660894 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.661254 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000358 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.661368 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000102 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.664663 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.003292 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.666093 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001421 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.666111 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000011 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.671867 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005753 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.671878 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.671882 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.671886 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.672916 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001026 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.672921 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.673185 3531398 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000260 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.673248 3531398 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000059 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.674902 3531398 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.086643 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.676008 3531398 simple_timer.cpp:55] [rocprofv3] output generation ::     0.101749 sec
   |-> [rocprofiler-sdk] W0506 23:17:04.676041 3531398 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.102744 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531398_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 2/10][Approximate profiling time left: 7 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_1.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:05.406112 3531405 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.073334 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:05.432450 3531405 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.451702 3531405 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:05.513400 3531405 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.080949 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.527224 3531405 generateRocpd.cpp:1042] writing SQL database for process 3531405 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:05.527634 3531405 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531405_results.db (UUID=000091af-9369-7369-be03-14988dc49fe7)
   |-> [rocprofiler-sdk] W0506 23:17:05.602870 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.003030 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.602897 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.603279 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000380 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.603386 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000096 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.606202 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.002814 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.607526 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001318 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.607540 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.613735 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.006192 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.613753 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000007 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.613759 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.613766 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000005 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.614817 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001049 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.614822 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.615091 3531405 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000264 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.615162 3531405 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000067 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.616845 3531405 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.089625 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.618001 3531405 simple_timer.cpp:55] [rocprofv3] output generation ::     0.103498 sec
   |-> [rocprofiler-sdk] W0506 23:17:05.618033 3531405 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.104606 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531405_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 3/10][Approximate profiling time left: 6 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_2.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:06.359627 3531425 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.075795 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:06.385194 3531425 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.402631 3531425 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:06.466339 3531425 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.081144 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.480490 3531425 generateRocpd.cpp:1042] writing SQL database for process 3531425 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:06.480908 3531425 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531425_results.db (UUID=000091af-9720-7720-b298-7cd4cc056760)
   |-> [rocprofiler-sdk] W0506 23:17:06.553220 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.002804 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.553233 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.553588 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000353 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.553708 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000108 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.556538 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.002828 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.557867 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001323 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.557881 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.563352 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005469 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.563362 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.563366 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.563371 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.564403 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001029 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.564407 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.564739 3531425 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000327 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.564847 3531425 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000097 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.566580 3531425 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.086094 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.567958 3531425 simple_timer.cpp:55] [rocprofv3] output generation ::     0.100490 sec
   |-> [rocprofiler-sdk] W0506 23:17:06.567989 3531425 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.101618 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531425_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 4/10][Approximate profiling time left: 5 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_3.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:07.308120 3531445 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.074192 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:07.333569 3531445 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.350774 3531445 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:07.413961 3531445 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.080392 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.428334 3531445 generateRocpd.cpp:1042] writing SQL database for process 3531445 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:07.428744 3531445 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531445_results.db (UUID=000091af-9ad6-7ad6-8b94-26376c0bac43)
   |-> [rocprofiler-sdk] W0506 23:17:07.501505 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.002853 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.501523 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.501897 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000371 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.502013 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000103 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.504620 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.002604 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.506019 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001392 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.506034 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000011 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.511123 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005086 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.511134 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000005 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.511140 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.511147 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000005 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.512170 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001021 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.512175 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.512434 3531445 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000254 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.512487 3531445 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000048 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.514028 3531445 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.085698 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.515080 3531445 simple_timer.cpp:55] [rocprofv3] output generation ::     0.100029 sec
   |-> [rocprofiler-sdk] W0506 23:17:07.515134 3531445 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.101142 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531445_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 5/10][Approximate profiling time left: 4 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_4.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:08.255707 3531454 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.074810 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:08.281943 3531454 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.302994 3531454 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:08.364188 3531454 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.082244 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.378373 3531454 generateRocpd.cpp:1042] writing SQL database for process 3531454 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:08.378897 3531454 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531454_results.db (UUID=000091af-9e89-7e89-8625-3864f32beb85)
   |-> [rocprofiler-sdk] W0506 23:17:08.451793 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.002881 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.451812 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.452165 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000351 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.452283 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000106 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.454758 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.002472 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.456096 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001332 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.456110 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000010 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.461426 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005313 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.461437 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.461441 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.461445 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.462484 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001037 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.462489 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.462751 3531454 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000258 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.462829 3531454 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000074 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.464391 3531454 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.086023 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.465445 3531454 simple_timer.cpp:55] [rocprofv3] output generation ::     0.100173 sec
   |-> [rocprofiler-sdk] W0506 23:17:08.465483 3531454 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.101269 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531454_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 6/10][Approximate profiling time left: 3 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_5.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:09.201575 3531462 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.073655 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:09.228148 3531462 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.246160 3531462 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:09.308118 3531462 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.079970 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.321893 3531462 generateRocpd.cpp:1042] writing SQL database for process 3531462 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:09.322301 3531462 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531462_results.db (UUID=000091af-a23c-723c-aec8-c9027401b8d8)
   |-> [rocprofiler-sdk] W0506 23:17:09.397193 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.003156 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.397213 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.397678 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000463 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.397834 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000142 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.399944 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.002107 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.401263 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001313 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.401276 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.406351 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005073 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.406361 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.406364 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.406369 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.407367 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.000996 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.407371 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.407621 3531462 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000246 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.407674 3531462 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000049 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.409153 3531462 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.087263 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.410407 3531462 simple_timer.cpp:55] [rocprofv3] output generation ::     0.101163 sec
   |-> [rocprofiler-sdk] W0506 23:17:09.410434 3531462 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.102286 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531462_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 7/10][Approximate profiling time left: 2 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_6.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:10.156460 3531472 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.073373 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:10.182170 3531472 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000001 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.200266 3531472 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:10.260409 3531472 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.078239 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.274423 3531472 generateRocpd.cpp:1042] writing SQL database for process 3531472 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:10.274836 3531472 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531472_results.db (UUID=000091af-a5f7-75f7-ae6d-168ebd8a1229)
   |-> [rocprofiler-sdk] W0506 23:17:10.345553 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.000176 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.345570 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.345927 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000355 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.346039 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000101 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.349179 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.003139 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.350509 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001323 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.350522 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.355876 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005351 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.355886 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.355890 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.355895 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.356897 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001001 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.356901 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.357153 3531472 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000247 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.357206 3531472 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000049 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.358772 3531472 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.084352 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.360008 3531472 simple_timer.cpp:55] [rocprofv3] output generation ::     0.098428 sec
   |-> [rocprofiler-sdk] W0506 23:17:10.360036 3531472 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.099599 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531472_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 8/10][Approximate profiling time left: 1 second]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_7.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:11.100787 3531492 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.073751 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:11.126027 3531492 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.144313 3531492 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:11.206161 3531492 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.080133 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.220324 3531492 generateRocpd.cpp:1042] writing SQL database for process 3531492 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:11.220750 3531492 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531492_results.db (UUID=000091af-a9a7-79a7-bbf9-72156d8d65f5)
   |-> [rocprofiler-sdk] W0506 23:17:11.289899 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.000185 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.289911 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.290246 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000332 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.290350 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000094 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.293503 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.003150 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.294825 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001315 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.294839 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.299893 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.005051 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.299902 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.299906 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.299911 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000004 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.300925 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.001012 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.300931 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.301203 3531492 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000265 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.301256 3531492 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000049 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.302715 3531492 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.082394 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.303767 3531492 simple_timer.cpp:55] [rocprofv3] output generation ::     0.096501 sec
   |-> [rocprofiler-sdk] W0506 23:17:11.303794 3531492 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.097605 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531492_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 9/10][Approximate profiling time left: 0 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_8.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:12.036972 3531510 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.075843 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:12.062860 3531510 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.083146 3531510 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:12.144294 3531510 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.081434 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.158487 3531510 generateRocpd.cpp:1042] writing SQL database for process 3531510 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:12.158913 3531510 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531510_results.db (UUID=000091af-ad4d-7d4d-bff6-45b505a251f7)
   |-> [rocprofiler-sdk] W0506 23:17:12.228660 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.000170 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.228675 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.229027 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000349 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.229133 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000096 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.232260 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.003124 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.233596 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001329 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.233610 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.238565 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.004953 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.238574 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.238578 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.238583 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.239580 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.000995 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.239584 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.239841 3531510 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000253 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.239896 3531510 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000051 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.241448 3531510 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.082965 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.242595 3531510 simple_timer.cpp:55] [rocprofv3] output generation ::     0.097277 sec
   |-> [rocprofiler-sdk] W0506 23:17:12.242623 3531510 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.098302 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531510_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
[Run 10/10][Approximate profiling time left: 0 seconds]...
[profiling] Current input file: tests/workloads/vcopy/RDNA35_HALO/perfmon/pmc_perf_9.yaml
   |-> [rocprofiler-sdk] [rocprofiler-compute] [rocprofiler_configure] (priority=1) is using rocprofiler-sdk v1.3.0 (1.3.0)
   |-> [rocprofiler-sdk] W0506 23:17:12.981836 3531519 simple_timer.cpp:55] [rocprofv3] tool initialization ::     0.074597 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool init
   |-> [rocprofiler-sdk] W0506 23:17:13.007626 3531519 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.028943 3531519 tool.cpp:2754] HSA version 1.18.0 initialized (instance=0)
   |-> [rocprofiler-sdk] vcopy testing on GCD 0
   |-> [rocprofiler-sdk] Finished allocating vectors on the CPU
   |-> [rocprofiler-sdk] Finished allocating vectors on the GPU
   |-> [rocprofiler-sdk] Finished copying vectors to the GPU
   |-> [rocprofiler-sdk] sw thinks it moved 1.000000 KB per wave
   |-> [rocprofiler-sdk] Total threads: 1048576, Grid Size: 4096 block Size:256, Wavefronts:16384:
   |-> [rocprofiler-sdk] Launching the  kernel on the GPU
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished executing kernel
   |-> [rocprofiler-sdk] Finished copying the output vector from the GPU to the CPU
   |-> [rocprofiler-sdk] Releasing GPU memory
   |-> [rocprofiler-sdk] Releasing CPU memory
   |-> [rocprofiler-sdk] W0506 23:17:13.090369 3531519 simple_timer.cpp:55] [rocprofv3] './tests/vcopy -n 1048576 -b 256 -i 3' ::     0.082741 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.104928 3531519 generateRocpd.cpp:1042] writing SQL database for process 3531519 on node 2467700534
   |-> [rocprofiler-sdk] E0506 23:17:13.105345 3531519 generateRocpd.cpp:1066] Opened result file: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/rocprof-apu3/3531519_results.db (UUID=000091af-b0ff-70ff-8c82-65ab62678124)
   |-> [rocprofiler-sdk] W0506 23:17:13.174600 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_string             ::     0.000138 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.174611 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_node          ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.174954 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_process       ::     0.000341 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.175064 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_agent         ::     0.000099 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.178577 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc           ::     0.003511 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.180380 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd kernel info        ::     0.001796 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.180393 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_region             ::     0.000009 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.184931 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_kernel_dispatch    ::     0.004535 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.184940 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event          ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.184944 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_copy        ::     0.000002 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.184949 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_memory_allocate    ::     0.000003 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.185944 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_info_pmc: kfd      ::     0.000993 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.185948 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_pmc_event: kfd     ::     0.000000 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.186198 3531519 simple_timer.cpp:55] SQLite3 generation :: rocpd_flush_pending      ::     0.000245 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.186249 3531519 simple_timer.cpp:55] SQLite3 generation :: SQL indexing             ::     0.000047 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.187763 3531519 simple_timer.cpp:55] SQLite3 generation :: total                    ::     0.082839 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.188965 3531519 simple_timer.cpp:55] [rocprofv3] output generation ::     0.097724 sec
   |-> [rocprofiler-sdk] W0506 23:17:13.188993 3531519 simple_timer.cpp:55] [rocprofv3] tool finalization ::     0.098594 sec
   |-> [rocprofiler-sdk] [rocprofiler-compute] In tool fini
   |-> [rocprofiler-sdk] [rocprofiler-compute] [write_counters] Counter collection data has been written to: tests/workloads/vcopy/RDNA35_HALO/out/pmc_1/3531519_native_counter_collection.csv
Intermediate results_*.csv generation from rocpd databases is deprecated and will be replaced with automatic .db file retention in a future release.
PC sampling data collection skipped as block 21 is not specified.
[roofline] Skipping roofline
