deepspeed_aio_common.cpp 13 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335
  1. // Copyright (c) Microsoft Corporation.
  2. // SPDX-License-Identifier: Apache-2.0
  3. // DeepSpeed Team
  4. /*
  5. Functionality for swapping optimizer tensors to/from (NVMe) storage devices.
  6. */
  7. #include <assert.h>
  8. #include <stdlib.h>
  9. #include <string.h>
  10. #include <fcntl.h>
  11. #include <libaio.h>
  12. #include <sys/mman.h>
  13. #include <sys/stat.h>
  14. #include <sys/types.h>
  15. #include <time.h>
  16. #include <unistd.h>
  17. #include <algorithm>
  18. #include <chrono>
  19. #include <cmath>
  20. #include <cstring>
  21. #include <fstream>
  22. #include <iostream>
  23. #include <limits>
  24. #include <numeric>
  25. #include <string>
  26. #include <vector>
  27. #include "deepspeed_aio_common.h"
  28. using namespace std;
  29. using namespace std::chrono;
  30. #define DEBUG_DS_AIO_PERF 0
  31. #define DEBUG_DS_AIO_SUBMIT_PERF 0
  32. static const std::string c_library_name = "deepspeed_aio";
  33. static void _report_aio_statistics(const char* tag,
  34. const std::vector<std::chrono::duration<double>>& latencies)
  35. __attribute__((unused));
  36. static void _report_aio_statistics(const char* tag,
  37. const std::vector<std::chrono::duration<double>>& latencies)
  38. {
  39. std::vector<double> lat_usec;
  40. for (auto& lat : latencies) { lat_usec.push_back(lat.count() * 1e6); }
  41. const auto min_lat = *(std::min_element(lat_usec.begin(), lat_usec.end()));
  42. const auto max_lat = *(std::max_element(lat_usec.begin(), lat_usec.end()));
  43. const auto avg_lat = std::accumulate(lat_usec.begin(), lat_usec.end(), 0) / lat_usec.size();
  44. std::cout << c_library_name << ": latency statistics(usec) " << tag
  45. << " min/max/avg = " << min_lat << " " << max_lat << " " << avg_lat << std::endl;
  46. }
  47. static void _get_aio_latencies(std::vector<std::chrono::duration<double>>& raw_latencies,
  48. struct deepspeed_aio_latency_t& summary_latencies)
  49. {
  50. std::vector<double> lat_usec;
  51. for (auto& lat : raw_latencies) { lat_usec.push_back(lat.count() * 1e6); }
  52. summary_latencies._min_usec = *(std::min_element(lat_usec.begin(), lat_usec.end()));
  53. summary_latencies._max_usec = *(std::max_element(lat_usec.begin(), lat_usec.end()));
  54. summary_latencies._avg_usec =
  55. std::accumulate(lat_usec.begin(), lat_usec.end(), 0) / lat_usec.size();
  56. }
  57. static void _do_io_submit_singles(const long long int n_iocbs,
  58. const long long int iocb_index,
  59. std::unique_ptr<aio_context>& aio_ctxt,
  60. std::vector<std::chrono::duration<double>>& submit_times)
  61. {
  62. for (auto i = 0; i < n_iocbs; ++i) {
  63. const auto st = std::chrono::high_resolution_clock::now();
  64. const auto submit_ret = io_submit(aio_ctxt->_io_ctxt, 1, aio_ctxt->_iocbs.data() + i);
  65. submit_times.push_back(std::chrono::high_resolution_clock::now() - st);
  66. #if DEBUG_DS_AIO_SUBMIT_PERF
  67. printf("submit(usec) %f io_index=%lld buf=%p len=%lu off=%llu \n",
  68. submit_times.back().count() * 1e6,
  69. iocb_index,
  70. aio_ctxt->_iocbs[i]->u.c.buf,
  71. aio_ctxt->_iocbs[i]->u.c.nbytes,
  72. aio_ctxt->_iocbs[i]->u.c.offset);
  73. #endif
  74. assert(submit_ret > 0);
  75. }
  76. }
  77. static void _do_io_submit_block(const long long int n_iocbs,
  78. const long long int iocb_index,
  79. std::unique_ptr<aio_context>& aio_ctxt,
  80. std::vector<std::chrono::duration<double>>& submit_times)
  81. {
  82. const auto st = std::chrono::high_resolution_clock::now();
  83. const auto submit_ret = io_submit(aio_ctxt->_io_ctxt, n_iocbs, aio_ctxt->_iocbs.data());
  84. submit_times.push_back(std::chrono::high_resolution_clock::now() - st);
  85. #if DEBUG_DS_AIO_SUBMIT_PERF
  86. printf("submit(usec) %f io_index=%lld nr=%lld buf=%p len=%lu off=%llu \n",
  87. submit_times.back().count() * 1e6,
  88. iocb_index,
  89. n_iocbs,
  90. aio_ctxt->_iocbs[0]->u.c.buf,
  91. aio_ctxt->_iocbs[0]->u.c.nbytes,
  92. aio_ctxt->_iocbs[0]->u.c.offset);
  93. #endif
  94. assert(submit_ret > 0);
  95. }
  96. static int _do_io_complete(const long long int min_completes,
  97. const long long int max_completes,
  98. std::unique_ptr<aio_context>& aio_ctxt,
  99. std::vector<std::chrono::duration<double>>& reap_times)
  100. {
  101. const auto start_time = std::chrono::high_resolution_clock::now();
  102. const auto n_completes = io_getevents(
  103. aio_ctxt->_io_ctxt, min_completes, max_completes, aio_ctxt->_io_events.data(), nullptr);
  104. reap_times.push_back(std::chrono::high_resolution_clock::now() - start_time);
  105. assert(n_completes >= min_completes);
  106. return n_completes;
  107. }
  108. void do_aio_operation_sequential(const bool read_op,
  109. std::unique_ptr<aio_context>& aio_ctxt,
  110. std::unique_ptr<io_xfer_ctxt>& xfer_ctxt,
  111. deepspeed_aio_config_t* config,
  112. deepspeed_aio_perf_t* perf)
  113. {
  114. struct io_prep_context prep_ctxt(read_op, xfer_ctxt, aio_ctxt->_block_size, &aio_ctxt->_iocbs);
  115. const auto num_io_blocks = static_cast<long long int>(
  116. ceil(static_cast<double>(xfer_ctxt->_num_bytes) / aio_ctxt->_block_size));
  117. #if DEBUG_DS_AIO_PERF
  118. const auto io_op_name = std::string(read_op ? "read" : "write");
  119. std::cout << c_library_name << ": start " << io_op_name << " " << xfer_ctxt->_num_bytes
  120. << " bytes with " << num_io_blocks << " io blocks" << std::endl;
  121. #endif
  122. std::vector<std::chrono::duration<double>> submit_times;
  123. std::vector<std::chrono::duration<double>> reap_times;
  124. const auto max_queue_bytes =
  125. static_cast<long long int>(aio_ctxt->_queue_depth * aio_ctxt->_block_size);
  126. auto start = std::chrono::high_resolution_clock::now();
  127. for (long long iocb_index = 0; iocb_index < num_io_blocks;
  128. iocb_index += aio_ctxt->_queue_depth) {
  129. const auto start_offset = iocb_index * aio_ctxt->_block_size;
  130. const auto start_buffer = (char*)xfer_ctxt->_mem_buffer + start_offset;
  131. const auto n_iocbs =
  132. min(static_cast<long long>(aio_ctxt->_queue_depth), (num_io_blocks - iocb_index));
  133. const auto num_bytes = min(max_queue_bytes, (xfer_ctxt->_num_bytes - start_offset));
  134. prep_ctxt.prep_iocbs(n_iocbs, num_bytes, start_buffer, start_offset);
  135. if (config->_single_submit) {
  136. _do_io_submit_singles(n_iocbs, iocb_index, aio_ctxt, submit_times);
  137. } else {
  138. _do_io_submit_block(n_iocbs, iocb_index, aio_ctxt, submit_times);
  139. }
  140. _do_io_complete(n_iocbs, n_iocbs, aio_ctxt, reap_times);
  141. }
  142. const std::chrono::duration<double> elapsed = std::chrono::high_resolution_clock::now() - start;
  143. if (perf) {
  144. _get_aio_latencies(submit_times, perf->_submit);
  145. _get_aio_latencies(reap_times, perf->_complete);
  146. perf->_e2e_usec = elapsed.count() * 1e6;
  147. perf->_e2e_rate_GB = (xfer_ctxt->_num_bytes / elapsed.count() / 1e9);
  148. }
  149. #if DEBUG_DS_AIO_PERF
  150. _report_aio_statistics("submit", submit_times);
  151. _report_aio_statistics("complete", reap_times);
  152. #endif
  153. #if DEBUG_DS_AIO_PERF
  154. std::cout << c_library_name << ": runtime(usec) " << elapsed.count() * 1e6
  155. << " rate(GB/sec) = " << (xfer_ctxt->_num_bytes / elapsed.count() / 1e9) << std::endl;
  156. #endif
  157. #if DEBUG_DS_AIO_PERF
  158. std::cout << c_library_name << ": finish " << io_op_name << " " << xfer_ctxt->_num_bytes
  159. << " bytes " << std::endl;
  160. #endif
  161. }
  162. void do_aio_operation_overlap(const bool read_op,
  163. std::unique_ptr<aio_context>& aio_ctxt,
  164. std::unique_ptr<io_xfer_ctxt>& xfer_ctxt,
  165. deepspeed_aio_config_t* config,
  166. deepspeed_aio_perf_t* perf)
  167. {
  168. struct io_prep_generator io_gen(read_op, xfer_ctxt, aio_ctxt->_block_size);
  169. #if DEBUG_DS_AIO_PERF
  170. const auto io_op_name = std::string(read_op ? "read" : "write");
  171. std::cout << c_library_name << ": start " << io_op_name << " " << xfer_ctxt->_num_bytes
  172. << " bytes with " << io_gen._num_io_blocks << " io blocks" << std::endl;
  173. #endif
  174. std::vector<std::chrono::duration<double>> submit_times;
  175. std::vector<std::chrono::duration<double>> reap_times;
  176. auto request_iocbs = aio_ctxt->_queue_depth;
  177. auto n_pending_iocbs = 0;
  178. const auto min_completes = 1;
  179. auto start = std::chrono::high_resolution_clock::now();
  180. while (true) {
  181. const auto n_iocbs = io_gen.prep_iocbs(request_iocbs - n_pending_iocbs, &aio_ctxt->_iocbs);
  182. if (n_iocbs > 0) {
  183. if (config->_single_submit) {
  184. _do_io_submit_singles(
  185. n_iocbs, (io_gen._next_iocb_index - n_iocbs), aio_ctxt, submit_times);
  186. } else {
  187. _do_io_submit_block(
  188. n_iocbs, (io_gen._next_iocb_index - n_iocbs), aio_ctxt, submit_times);
  189. }
  190. }
  191. n_pending_iocbs += n_iocbs;
  192. assert(n_pending_iocbs <= aio_ctxt->_queue_depth);
  193. if (n_pending_iocbs == 0) { break; }
  194. const auto n_complete =
  195. _do_io_complete(min_completes, n_pending_iocbs, aio_ctxt, reap_times);
  196. n_pending_iocbs -= n_complete;
  197. }
  198. const std::chrono::duration<double> elapsed = std::chrono::high_resolution_clock::now() - start;
  199. if (perf) {
  200. _get_aio_latencies(submit_times, perf->_submit);
  201. _get_aio_latencies(reap_times, perf->_complete);
  202. perf->_e2e_usec = elapsed.count() * 1e6;
  203. perf->_e2e_rate_GB = (xfer_ctxt->_num_bytes / elapsed.count() / 1e9);
  204. }
  205. #if DEBUG_DS_AIO_PERF
  206. _report_aio_statistics("submit", submit_times);
  207. _report_aio_statistics("complete", reap_times);
  208. #endif
  209. #if DEBUG_DS_AIO_PERF
  210. std::cout << c_library_name << ": runtime(usec) " << elapsed.count() * 1e6
  211. << " rate(GB/sec) = " << (xfer_ctxt->_num_bytes / elapsed.count() / 1e9) << std::endl;
  212. #endif
  213. #if DEBUG_DS_AIO_PERF
  214. std::cout << c_library_name << ": finish " << io_op_name << " " << xfer_ctxt->_num_bytes
  215. << " bytes " << std::endl;
  216. #endif
  217. }
  218. void report_file_error(const char* filename, const std::string file_op, const int error_code)
  219. {
  220. std::string err_msg = file_op + std::string(" failed on ") + std::string(filename) +
  221. " error = " + std::to_string(error_code);
  222. std::cerr << c_library_name << ": " << err_msg << std::endl;
  223. }
  224. int open_file(const char* filename, const bool read_op)
  225. {
  226. const int flags = read_op ? (O_RDONLY | O_DIRECT) : (O_WRONLY | O_CREAT | O_DIRECT);
  227. const int mode = 0600;
  228. const auto fd = open(filename, flags, mode);
  229. if (fd == -1) {
  230. const auto error_code = errno;
  231. const auto error_msg = read_op ? " open for read " : " open for write ";
  232. report_file_error(filename, error_msg, error_code);
  233. return -1;
  234. }
  235. return fd;
  236. }
  237. int regular_read(const char* filename, std::vector<char>& buffer)
  238. {
  239. long long int num_bytes;
  240. const auto f_size = get_file_size(filename, num_bytes);
  241. assert(f_size != -1);
  242. buffer.resize(num_bytes);
  243. const auto fd = open(filename, O_RDONLY, 0600);
  244. assert(fd != -1);
  245. long long int read_bytes = 0;
  246. auto r = 0;
  247. do {
  248. const auto buffer_ptr = buffer.data() + read_bytes;
  249. const auto bytes_to_read = num_bytes - read_bytes;
  250. r = read(fd, buffer_ptr, bytes_to_read);
  251. read_bytes += r;
  252. } while (r > 0);
  253. if (read_bytes != num_bytes) {
  254. std::cerr << "read error "
  255. << " read_bytes (read) = " << read_bytes << " num_bytes (fstat) = " << num_bytes
  256. << std::endl;
  257. }
  258. assert(read_bytes == num_bytes);
  259. close(fd);
  260. return 0;
  261. }
  262. static bool _validate_buffer(const char* filename, void* aio_buffer, const long long int num_bytes)
  263. {
  264. std::vector<char> regular_buffer;
  265. const auto reg_ret = regular_read(filename, regular_buffer);
  266. assert(0 == reg_ret);
  267. std::cout << "regular read of " << filename << " returned " << regular_buffer.size() << " bytes"
  268. << std::endl;
  269. if (static_cast<long long int>(regular_buffer.size()) != num_bytes) { return false; }
  270. return (0 == memcmp(aio_buffer, regular_buffer.data(), regular_buffer.size()));
  271. }
  272. bool validate_aio_operation(const bool read_op,
  273. const char* filename,
  274. void* aio_buffer,
  275. const long long int num_bytes)
  276. {
  277. const auto msg_suffix = std::string("deepspeed_aio_") +
  278. std::string(read_op ? "read()" : "write()") +
  279. std::string("using read()");
  280. if (false == _validate_buffer(filename, aio_buffer, num_bytes)) {
  281. std::cout << "Fail: correctness of " << msg_suffix << std::endl;
  282. return false;
  283. }
  284. std::cout << "Pass: correctness of " << msg_suffix << std::endl;
  285. return true;
  286. }