io_u.c

   1 #include <unistd.h>
   2 #include <fcntl.h>
   3 #include <string.h>
   4 #include <signal.h>
   5 #include <time.h>
   6 #include <assert.h>
   7
   8 #include "fio.h"
   9
  10 /*
  11  * Change this define to play with the timeout handling
  12  */
  13 #undef FIO_USE_TIMEOUT
  14
  15 struct io_completion_data {
  16         int nr;                         /* input */
  17
  18         int error;                      /* output */
  19         unsigned long bytes_done[2];    /* output */
  20         struct timeval time;            /* output */
  21 };
  22
  23 /*
  24  * The ->file_map[] contains a map of blocks we have or have not done io
  25  * to yet. Used to make sure we cover the entire range in a fair fashion.
  26  */
  27 static int random_map_free(struct thread_data *td, struct fio_file *f,
  28                            unsigned long long block)
  29 {
  30         unsigned int idx = RAND_MAP_IDX(td, f, block);
  31         unsigned int bit = RAND_MAP_BIT(td, f, block);
  32
  33         return (f->file_map[idx] & (1UL << bit)) == 0;
  34 }
  35
  36 /*
  37  * Mark a given offset as used in the map.
  38  */
  39 static void mark_random_map(struct thread_data *td, struct io_u *io_u)
  40 {
  41         unsigned int min_bs = td->o.rw_min_bs;
  42         struct fio_file *f = io_u->file;
  43         unsigned long long block;
  44         unsigned int blocks;
  45         unsigned int nr_blocks;
  46
  47         block = io_u->offset / (unsigned long long) min_bs;
  48         blocks = 0;
  49         nr_blocks = (io_u->buflen + min_bs - 1) / min_bs;
  50
  51         while (blocks < nr_blocks) {
  52                 unsigned int idx, bit;
  53
  54                 /*
  55                  * If we have a mixed random workload, we may
  56                  * encounter blocks we already did IO to.
  57                  */
  58                 if (!td->o.ddir_nr && !random_map_free(td, f, block))
  59                         break;
  60
  61                 idx = RAND_MAP_IDX(td, f, block);
  62                 bit = RAND_MAP_BIT(td, f, block);
  63
  64                 fio_assert(td, idx < f->num_maps);
  65
  66                 f->file_map[idx] |= (1UL << bit);
  67                 block++;
  68                 blocks++;
  69         }
  70
  71         if ((blocks * min_bs) < io_u->buflen)
  72                 io_u->buflen = blocks * min_bs;
  73 }
  74
  75 /*
  76  * Return the next free block in the map.
  77  */
  78 static int get_next_free_block(struct thread_data *td, struct fio_file *f,
  79                                unsigned long long *b)
  80 {
  81         int i;
  82
  83         i = f->last_free_lookup;
  84         *b = (i * BLOCKS_PER_MAP);
  85         while ((*b) * td->o.rw_min_bs < f->real_file_size) {
  86                 if (f->file_map[i] != -1UL) {
  87                         *b += ffz(f->file_map[i]);
  88                         f->last_free_lookup = i;
  89                         return 0;
  90                 }
  91
  92                 *b += BLOCKS_PER_MAP;
  93                 i++;
  94         }
  95
  96         return 1;
  97 }
  98
  99 static int get_next_rand_offset(struct thread_data *td, struct fio_file *f,
 100                                 int ddir, unsigned long long *b)
 101 {
 102         unsigned long long max_blocks = f->io_size / td->o.min_bs[ddir];
 103         unsigned long long r, rb;
 104         int loops = 5;
 105
 106         do {
 107                 r = os_random_long(&td->random_state);
 108                 if (!max_blocks)
 109                         *b = 0;
 110                 else
 111                         *b = ((max_blocks - 1) * r / (unsigned long long) (RAND_MAX+1.0));
 112                 if (td->o.norandommap)
 113                         break;
 114                 rb = *b + (f->file_offset / td->o.min_bs[ddir]);
 115                 loops--;
 116         } while (!random_map_free(td, f, rb) && loops);
 117
 118         /*
 119          * if we failed to retrieve a truly random offset within
 120          * the loops assigned, see if there are free ones left at all
 121          */
 122         if (!loops && get_next_free_block(td, f, b))
 123                 return 1;
 124
 125         return 0;
 126 }
 127
 128 /*
 129  * For random io, generate a random new block and see if it's used. Repeat
 130  * until we find a free one. For sequential io, just return the end of
 131  * the last io issued.
 132  */
 133 static int get_next_offset(struct thread_data *td, struct io_u *io_u)
 134 {
 135         struct fio_file *f = io_u->file;
 136         const int ddir = io_u->ddir;
 137         unsigned long long b;
 138
 139         if (td_random(td) && (td->o.ddir_nr && !--td->ddir_nr)) {
 140                 td->ddir_nr = td->o.ddir_nr;
 141
 142                 if (get_next_rand_offset(td, f, ddir, &b))
 143                         return 1;
 144         } else {
 145                 if (f->last_pos >= f->real_file_size)
 146                         return 1;
 147
 148                 b = f->last_pos / td->o.min_bs[ddir];
 149         }
 150
 151         io_u->offset = (b * td->o.min_bs[ddir]) + f->file_offset;
 152         if (io_u->offset >= f->real_file_size)
 153                 return 1;
 154
 155         return 0;
 156 }
 157
 158 static unsigned int get_next_buflen(struct thread_data *td, struct io_u *io_u)
 159 {
 160         const int ddir = io_u->ddir;
 161         unsigned int buflen;
 162         long r;
 163
 164         if (td->o.min_bs[ddir] == td->o.max_bs[ddir])
 165                 buflen = td->o.min_bs[ddir];
 166         else {
 167                 r = os_random_long(&td->bsrange_state);
 168                 buflen = (unsigned int) (1 + (double) (td->o.max_bs[ddir] - 1) * r / (RAND_MAX + 1.0));
 169                 if (!td->o.bs_unaligned)
 170                         buflen = (buflen + td->o.min_bs[ddir] - 1) & ~(td->o.min_bs[ddir] - 1);
 171         }
 172
 173         return buflen;
 174 }
 175
 176 static void set_rwmix_bytes(struct thread_data *td)
 177 {
 178         unsigned long long rbytes;
 179         unsigned int diff;
 180
 181         /*
 182          * we do time or byte based switch. this is needed because
 183          * buffered writes may issue a lot quicker than they complete,
 184          * whereas reads do not.
 185          */
 186         rbytes = td->io_bytes[td->rwmix_ddir] - td->rwmix_bytes;
 187         diff = td->o.rwmix[td->rwmix_ddir ^ 1];
 188
 189         td->rwmix_bytes = td->io_bytes[td->rwmix_ddir] + (rbytes * ((100 - diff)) / diff);
 190 }
 191
 192 static inline enum fio_ddir get_rand_ddir(struct thread_data *td)
 193 {
 194         unsigned int v;
 195         long r;
 196
 197         r = os_random_long(&td->rwmix_state);
 198         v = 1 + (int) (100.0 * (r / (RAND_MAX + 1.0)));
 199         if (v < td->o.rwmix[DDIR_READ])
 200                 return DDIR_READ;
 201
 202         return DDIR_WRITE;
 203 }
 204
 205 /*
 206  * Return the data direction for the next io_u. If the job is a
 207  * mixed read/write workload, check the rwmix cycle and switch if
 208  * necessary.
 209  */
 210 static enum fio_ddir get_rw_ddir(struct thread_data *td)
 211 {
 212         if (td_rw(td)) {
 213                 struct timeval now;
 214                 unsigned long elapsed;
 215                 unsigned int cycle;
 216
 217                 fio_gettime(&now, NULL);
 218                 elapsed = mtime_since_now(&td->rwmix_switch);
 219
 220                 /*
 221                  * if this is the first cycle, make it shorter
 222                  */
 223                 cycle = td->o.rwmixcycle;
 224                 if (!td->rwmix_bytes)
 225                         cycle /= 10;
 226
 227                 /*
 228                  * Check if it's time to seed a new data direction.
 229                  */
 230                 if (elapsed >= cycle ||
 231                     td->io_bytes[td->rwmix_ddir] >= td->rwmix_bytes) {
 232                         unsigned long long max_bytes;
 233                         enum fio_ddir ddir;
 234
 235                         /*
 236                          * Put a top limit on how many bytes we do for
 237                          * one data direction, to avoid overflowing the
 238                          * ranges too much
 239                          */
 240                         ddir = get_rand_ddir(td);
 241                         max_bytes = td->this_io_bytes[ddir];
 242                         if (max_bytes >= (td->o.size * td->o.rwmix[ddir] / 100)) {
 243                                 if (!td->rw_end_set[ddir]) {
 244                                         td->rw_end_set[ddir] = 1;
 245                                         memcpy(&td->rw_end[ddir], &now, sizeof(now));
 246                                 }
 247                                 ddir ^= 1;
 248                         }
 249
 250                         if (ddir != td->rwmix_ddir)
 251                                 set_rwmix_bytes(td);
 252
 253                         td->rwmix_ddir = ddir;
 254                         memcpy(&td->rwmix_switch, &now, sizeof(now));
 255                 }
 256                 return td->rwmix_ddir;
 257         } else if (td_read(td))
 258                 return DDIR_READ;
 259         else
 260                 return DDIR_WRITE;
 261 }
 262
 263 void put_io_u(struct thread_data *td, struct io_u *io_u)
 264 {
 265         assert((io_u->flags & IO_U_F_FREE) == 0);
 266         io_u->flags |= IO_U_F_FREE;
 267
 268         if (io_u->file)
 269                 put_file(td, io_u->file);
 270
 271         io_u->file = NULL;
 272         list_del(&io_u->list);
 273         list_add(&io_u->list, &td->io_u_freelist);
 274         td->cur_depth--;
 275 }
 276
 277 void requeue_io_u(struct thread_data *td, struct io_u **io_u)
 278 {
 279         struct io_u *__io_u = *io_u;
 280
 281         __io_u->flags |= IO_U_F_FREE;
 282         __io_u->flags &= ~IO_U_F_FLIGHT;
 283
 284         list_del(&__io_u->list);
 285         list_add_tail(&__io_u->list, &td->io_u_requeues);
 286         td->cur_depth--;
 287         *io_u = NULL;
 288 }
 289
 290 static int fill_io_u(struct thread_data *td, struct io_u *io_u)
 291 {
 292         /*
 293          * If using an iolog, grab next piece if any available.
 294          */
 295         if (td->o.read_iolog_file)
 296                 return read_iolog_get(td, io_u);
 297
 298         /*
 299          * see if it's time to sync
 300          */
 301         if (td->o.fsync_blocks &&
 302            !(td->io_issues[DDIR_WRITE] % td->o.fsync_blocks) &&
 303              td->io_issues[DDIR_WRITE] && should_fsync(td)) {
 304                 io_u->ddir = DDIR_SYNC;
 305                 goto out;
 306         }
 307
 308         io_u->ddir = get_rw_ddir(td);
 309
 310         /*
 311          * No log, let the seq/rand engine retrieve the next buflen and
 312          * position.
 313          */
 314         if (get_next_offset(td, io_u))
 315                 return 1;
 316
 317         io_u->buflen = get_next_buflen(td, io_u);
 318         if (!io_u->buflen)
 319                 return 1;
 320
 321         /*
 322          * mark entry before potentially trimming io_u
 323          */
 324         if (td_random(td) && !td->o.norandommap)
 325                 mark_random_map(td, io_u);
 326
 327         /*
 328          * If using a write iolog, store this entry.
 329          */
 330 out:
 331         if (td->o.write_iolog_file)
 332                 write_iolog_put(td, io_u);
 333
 334         return 0;
 335 }
 336
 337 void io_u_mark_depth(struct thread_data *td, struct io_u *io_u)
 338 {
 339         int index = 0;
 340
 341         if (io_u->ddir == DDIR_SYNC)
 342                 return;
 343
 344         switch (td->cur_depth) {
 345         default:
 346                 index++;
 347         case 32 ... 63:
 348                 index++;
 349         case 16 ... 31:
 350                 index++;
 351         case 8 ... 15:
 352                 index++;
 353         case 4 ... 7:
 354                 index++;
 355         case 2 ... 3:
 356                 index++;
 357         case 1:
 358                 break;
 359         }
 360
 361         td->ts.io_u_map[index]++;
 362         td->ts.total_io_u[io_u->ddir]++;
 363 }
 364
 365 static void io_u_mark_latency(struct thread_data *td, unsigned long msec)
 366 {
 367         int index = 0;
 368
 369         switch (msec) {
 370         default:
 371                 index++;
 372         case 1000 ... 1999:
 373                 index++;
 374         case 750 ... 999:
 375                 index++;
 376         case 500 ... 749:
 377                 index++;
 378         case 250 ... 499:
 379                 index++;
 380         case 100 ... 249:
 381                 index++;
 382         case 50 ... 99:
 383                 index++;
 384         case 20 ... 49:
 385                 index++;
 386         case 10 ... 19:
 387                 index++;
 388         case 4 ... 9:
 389                 index++;
 390         case 2 ... 3:
 391                 index++;
 392         case 0 ... 1:
 393                 break;
 394         }
 395
 396         td->ts.io_u_lat[index]++;
 397 }
 398
 399 /*
 400  * Get next file to service by choosing one at random
 401  */
 402 static struct fio_file *get_next_file_rand(struct thread_data *td, int goodf,
 403                                            int badf)
 404 {
 405         struct fio_file *f;
 406         int fno;
 407
 408         do {
 409                 long r = os_random_long(&td->next_file_state);
 410
 411                 fno = (unsigned int) ((double) td->o.nr_files * (r / (RAND_MAX + 1.0)));
 412                 f = &td->files[fno];
 413                 if (f->flags & FIO_FILE_DONE)
 414                         continue;
 415
 416                 if ((!goodf || (f->flags & goodf)) && !(f->flags & badf))
 417                         return f;
 418         } while (1);
 419 }
 420
 421 /*
 422  * Get next file to service by doing round robin between all available ones
 423  */
 424 static struct fio_file *get_next_file_rr(struct thread_data *td, int goodf,
 425                                          int badf)
 426 {
 427         unsigned int old_next_file = td->next_file;
 428         struct fio_file *f;
 429
 430         do {
 431                 f = &td->files[td->next_file];
 432
 433                 td->next_file++;
 434                 if (td->next_file >= td->o.nr_files)
 435                         td->next_file = 0;
 436
 437                 if (f->flags & FIO_FILE_DONE) {
 438                         f = NULL;
 439                         continue;
 440                 }
 441
 442                 if ((!goodf || (f->flags & goodf)) && !(f->flags & badf))
 443                         break;
 444
 445                 f = NULL;
 446         } while (td->next_file != old_next_file);
 447
 448         return f;
 449 }
 450
 451 static struct fio_file *get_next_file(struct thread_data *td)
 452 {
 453         struct fio_file *f;
 454
 455         assert(td->o.nr_files <= td->files_index);
 456
 457         if (!td->nr_open_files || td->nr_done_files >= td->o.nr_files)
 458                 return NULL;
 459
 460         f = td->file_service_file;
 461         if (f && (f->flags & FIO_FILE_OPEN) && td->file_service_left--)
 462                 return f;
 463
 464         if (td->o.file_service_type == FIO_FSERVICE_RR)
 465                 f = get_next_file_rr(td, FIO_FILE_OPEN, FIO_FILE_CLOSING);
 466         else
 467                 f = get_next_file_rand(td, FIO_FILE_OPEN, FIO_FILE_CLOSING);
 468
 469         td->file_service_file = f;
 470         td->file_service_left = td->file_service_nr - 1;
 471         return f;
 472 }
 473
 474 static struct fio_file *find_next_new_file(struct thread_data *td)
 475 {
 476         struct fio_file *f;
 477
 478         if (!td->nr_open_files || td->nr_done_files >= td->o.nr_files)
 479                 return NULL;
 480
 481         if (td->o.file_service_type == FIO_FSERVICE_RR)
 482                 f = get_next_file_rr(td, 0, FIO_FILE_OPEN);
 483         else
 484                 f = get_next_file_rand(td, 0, FIO_FILE_OPEN);
 485
 486         return f;
 487 }
 488
 489 struct io_u *__get_io_u(struct thread_data *td)
 490 {
 491         struct io_u *io_u = NULL;
 492
 493         if (!list_empty(&td->io_u_requeues))
 494                 io_u = list_entry(td->io_u_requeues.next, struct io_u, list);
 495         else if (!queue_full(td)) {
 496                 io_u = list_entry(td->io_u_freelist.next, struct io_u, list);
 497
 498                 io_u->buflen = 0;
 499                 io_u->resid = 0;
 500                 io_u->file = NULL;
 501                 io_u->end_io = NULL;
 502         }
 503
 504         if (io_u) {
 505                 assert(io_u->flags & IO_U_F_FREE);
 506                 io_u->flags &= ~IO_U_F_FREE;
 507
 508                 io_u->error = 0;
 509                 list_del(&io_u->list);
 510                 list_add(&io_u->list, &td->io_u_busylist);
 511                 td->cur_depth++;
 512         }
 513
 514         return io_u;
 515 }
 516
 517 /*
 518  * Return an io_u to be processed. Gets a buflen and offset, sets direction,
 519  * etc. The returned io_u is fully ready to be prepped and submitted.
 520  */
 521 struct io_u *get_io_u(struct thread_data *td)
 522 {
 523         struct fio_file *f;
 524         struct io_u *io_u;
 525         int ret;
 526
 527         io_u = __get_io_u(td);
 528         if (!io_u)
 529                 return NULL;
 530
 531         /*
 532          * from a requeue, io_u already setup
 533          */
 534         if (io_u->file)
 535                 goto out;
 536
 537         do {
 538                 f = get_next_file(td);
 539                 if (!f) {
 540                         put_io_u(td, io_u);
 541                         return NULL;
 542                 }
 543
 544 set_file:
 545                 io_u->file = f;
 546                 get_file(f);
 547
 548                 if (!fill_io_u(td, io_u))
 549                         break;
 550
 551                 /*
 552                  * td_io_close() does a put_file() as well, so no need to
 553                  * do that here.
 554                  */
 555                 io_u->file = NULL;
 556                 td_io_close_file(td, f);
 557                 f->flags |= FIO_FILE_DONE;
 558                 td->nr_done_files++;
 559
 560                 /*
 561                  * probably not the right place to do this, but see
 562                  * if we need to open a new file
 563                  */
 564                 if (td->nr_open_files < td->o.open_files &&
 565                     td->o.open_files != td->o.nr_files) {
 566                         f = find_next_new_file(td);
 567
 568                         if (!f || (ret = td_io_open_file(td, f))) {
 569                                 put_io_u(td, io_u);
 570                                 return NULL;
 571                         }
 572                         goto set_file;
 573                 }
 574         } while (1);
 575
 576         if (td->zone_bytes >= td->o.zone_size) {
 577                 td->zone_bytes = 0;
 578                 f->last_pos += td->o.zone_skip;
 579         }
 580
 581         if (io_u->ddir != DDIR_SYNC) {
 582                 if (!io_u->buflen) {
 583                         put_io_u(td, io_u);
 584                         return NULL;
 585                 }
 586
 587                 f->last_pos = io_u->offset + io_u->buflen;
 588
 589                 if (td->o.verify != VERIFY_NONE)
 590                         populate_verify_io_u(td, io_u);
 591         }
 592
 593         /*
 594          * Set io data pointers.
 595          */
 596         io_u->endpos = io_u->offset + io_u->buflen;
 597 out:
 598         io_u->xfer_buf = io_u->buf;
 599         io_u->xfer_buflen = io_u->buflen;
 600
 601         if (td_io_prep(td, io_u)) {
 602                 put_io_u(td, io_u);
 603                 return NULL;
 604         }
 605
 606         fio_gettime(&io_u->start_time, NULL);
 607         return io_u;
 608 }
 609
 610 void io_u_log_error(struct thread_data *td, struct io_u *io_u)
 611 {
 612         const char *msg[] = { "read", "write", "sync" };
 613
 614         log_err("fio: io_u error");
 615
 616         if (io_u->file)
 617                 log_err(" on file %s", io_u->file->file_name);
 618
 619         log_err(": %s\n", strerror(io_u->error));
 620
 621         log_err("     %s offset=%llu, buflen=%lu\n", msg[io_u->ddir], io_u->offset, io_u->xfer_buflen);
 622
 623         if (!td->error)
 624                 td_verror(td, io_u->error, "io_u error");
 625 }
 626
 627 static void io_completed(struct thread_data *td, struct io_u *io_u,
 628                          struct io_completion_data *icd)
 629 {
 630         unsigned long msec;
 631
 632         assert(io_u->flags & IO_U_F_FLIGHT);
 633         io_u->flags &= ~IO_U_F_FLIGHT;
 634
 635         if (io_u->ddir == DDIR_SYNC) {
 636                 td->last_was_sync = 1;
 637                 return;
 638         }
 639
 640         td->last_was_sync = 0;
 641
 642         if (!io_u->error) {
 643                 unsigned int bytes = io_u->buflen - io_u->resid;
 644                 const enum fio_ddir idx = io_u->ddir;
 645                 int ret;
 646
 647                 td->io_blocks[idx]++;
 648                 td->io_bytes[idx] += bytes;
 649                 td->zone_bytes += bytes;
 650                 td->this_io_bytes[idx] += bytes;
 651
 652                 io_u->file->last_completed_pos = io_u->endpos;
 653
 654                 msec = mtime_since(&io_u->issue_time, &icd->time);
 655
 656                 add_clat_sample(td, idx, msec);
 657                 add_bw_sample(td, idx, &icd->time);
 658                 io_u_mark_latency(td, msec);
 659
 660                 if (td_write(td) && idx == DDIR_WRITE &&
 661                     td->o.verify != VERIFY_NONE)
 662                         log_io_piece(td, io_u);
 663
 664                 icd->bytes_done[idx] += bytes;
 665
 666                 if (io_u->end_io) {
 667                         ret = io_u->end_io(td, io_u);
 668                         if (ret && !icd->error)
 669                                 icd->error = ret;
 670                 }
 671         } else {
 672                 icd->error = io_u->error;
 673                 io_u_log_error(td, io_u);
 674         }
 675 }
 676
 677 static void init_icd(struct io_completion_data *icd, int nr)
 678 {
 679         fio_gettime(&icd->time, NULL);
 680
 681         icd->nr = nr;
 682
 683         icd->error = 0;
 684         icd->bytes_done[0] = icd->bytes_done[1] = 0;
 685 }
 686
 687 static void ios_completed(struct thread_data *td,
 688                           struct io_completion_data *icd)
 689 {
 690         struct io_u *io_u;
 691         int i;
 692
 693         for (i = 0; i < icd->nr; i++) {
 694                 io_u = td->io_ops->event(td, i);
 695
 696                 io_completed(td, io_u, icd);
 697                 put_io_u(td, io_u);
 698         }
 699 }
 700
 701 /*
 702  * Complete a single io_u for the sync engines.
 703  */
 704 long io_u_sync_complete(struct thread_data *td, struct io_u *io_u)
 705 {
 706         struct io_completion_data icd;
 707
 708         init_icd(&icd, 1);
 709         io_completed(td, io_u, &icd);
 710         put_io_u(td, io_u);
 711
 712         if (!icd.error)
 713                 return icd.bytes_done[0] + icd.bytes_done[1];
 714
 715         td_verror(td, icd.error, "io_u_sync_complete");
 716         return -1;
 717 }
 718
 719 /*
 720  * Called to complete min_events number of io for the async engines.
 721  */
 722 long io_u_queued_complete(struct thread_data *td, int min_events)
 723 {
 724         struct io_completion_data icd;
 725         struct timespec *tvp = NULL;
 726         int ret;
 727         struct timespec ts = { .tv_sec = 0, .tv_nsec = 0, };
 728
 729         if (!min_events)
 730                 tvp = &ts;
 731
 732         ret = td_io_getevents(td, min_events, td->cur_depth, tvp);
 733         if (ret < 0) {
 734                 td_verror(td, -ret, "td_io_getevents");
 735                 return ret;
 736         } else if (!ret)
 737                 return ret;
 738
 739         init_icd(&icd, ret);
 740         ios_completed(td, &icd);
 741         if (!icd.error)
 742                 return icd.bytes_done[0] + icd.bytes_done[1];
 743
 744         td_verror(td, icd.error, "io_u_queued_complete");
 745         return -1;
 746 }
 747
 748 /*
 749  * Call when io_u is really queued, to update the submission latency.
 750  */
 751 void io_u_queued(struct thread_data *td, struct io_u *io_u)
 752 {
 753         unsigned long slat_time;
 754
 755         slat_time = mtime_since(&io_u->start_time, &io_u->issue_time);
 756         add_slat_sample(td, io_u->ddir, slat_time);
 757 }
 758
 759 #ifdef FIO_USE_TIMEOUT
 760 void io_u_set_timeout(struct thread_data *td)
 761 {
 762         assert(td->cur_depth);
 763
 764         td->timer.it_interval.tv_sec = 0;
 765         td->timer.it_interval.tv_usec = 0;
 766         td->timer.it_value.tv_sec = IO_U_TIMEOUT + IO_U_TIMEOUT_INC;
 767         td->timer.it_value.tv_usec = 0;
 768         setitimer(ITIMER_REAL, &td->timer, NULL);
 769         fio_gettime(&td->timeout_end, NULL);
 770 }
 771
 772 static void io_u_dump(struct io_u *io_u)
 773 {
 774         unsigned long t_start = mtime_since_now(&io_u->start_time);
 775         unsigned long t_issue = mtime_since_now(&io_u->issue_time);
 776
 777         log_err("io_u=%p, t_start=%lu, t_issue=%lu\n", io_u, t_start, t_issue);
 778         log_err("  buf=%p/%p, len=%lu/%lu, offset=%llu\n", io_u->buf, io_u->xfer_buf, io_u->buflen, io_u->xfer_buflen, io_u->offset);
 779         log_err("  ddir=%d, fname=%s\n", io_u->ddir, io_u->file->file_name);
 780 }
 781 #else
 782 void io_u_set_timeout(struct thread_data fio_unused *td)
 783 {
 784 }
 785 #endif
 786
 787 #ifdef FIO_USE_TIMEOUT
 788 static void io_u_timeout_handler(int fio_unused sig)
 789 {
 790         struct thread_data *td, *__td;
 791         pid_t pid = getpid();
 792         struct list_head *entry;
 793         struct io_u *io_u;
 794         int i;
 795
 796         log_err("fio: io_u timeout\n");
 797
 798         /*
 799          * TLS would be nice...
 800          */
 801         td = NULL;
 802         for_each_td(__td, i) {
 803                 if (__td->pid == pid) {
 804                         td = __td;
 805                         break;
 806                 }
 807         }
 808
 809         if (!td) {
 810                 log_err("fio: io_u timeout, can't find job\n");
 811                 exit(1);
 812         }
 813
 814         if (!td->cur_depth) {
 815                 log_err("fio: timeout without pending work?\n");
 816                 return;
 817         }
 818
 819         log_err("fio: io_u timeout: job=%s, pid=%d\n", td->o.name, td->pid);
 820
 821         list_for_each(entry, &td->io_u_busylist) {
 822                 io_u = list_entry(entry, struct io_u, list);
 823
 824                 io_u_dump(io_u);
 825         }
 826
 827         td_verror(td, ETIMEDOUT, "io_u timeout");
 828         exit(1);
 829 }
 830 #endif
 831
 832 void io_u_init_timeout(void)
 833 {
 834 #ifdef FIO_USE_TIMEOUT
 835         signal(SIGALRM, io_u_timeout_handler);
 836 #endif
 837 }