deepspeed_aio_common.cpp 13 KB

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