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