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