Hi all,

I am using Time::HiRes to create timestamps in my program which I insert into the DB with the record, rather than using the DB's timestamp function, because of the unpredictability of when the record might actually be inserted.

In my logger: 

use Time::HiRes qw/ gettimeofday /;

sub log {
    my ( $self, $args ) = @_;

    my $data = {
        time    => join('.', ( gettimeofday )),
        pid     => $args->{'pid'},
        action  => $args->{'action'},
    };

    $self->write( $data );
}

[download]
which is called, sequentially, three times, with actions 'finish', 'sleep', 'complete'.

Most of the time the results are as expected, but sometimes the order is off. Example (note that the DB insert IDs follow the order of the program): 

mysql> select id, pid, action, from_unixtime(time) from queue_logger o
+rder by id;
+-------+-------+----------+----------------------------+
| id    | pid   | action   | from_unixtime(time)        |
+-------+-------+----------+----------------------------+
| 83640 | 15218 | finish   | 2016-05-06 12:38:49.834030 |
| 83642 | 15218 | sleep    | 2016-05-06 12:38:49.251690 | <- timestamp
+ed first, but inserted second!
| 83683 | 15218 | complete | 2016-05-06 12:39:19.707134 |
+-------+-------+----------+----------------------------+

[download]
Now I have read up on Time::HiRes and the various gotchas that occur related to the system clock. But I don't get this because (a) the code is doing the calls sequentially (b) the DB is inserting the rows in the same sequence, but (c) the timestamps are not in the same order.

Any help to understand what's going on here would be greatly appreciated.


The way forward always starts with a minimal test.

In reply to Time::HiRes gettimeofday producing timestamps out of order by 1nickt

Title:
Use:  <p> text here (a paragraph) </p>
and:  <code> code here </code>
to format your post, it's "PerlMonks-approved HTML":


  • Posts are HTML formatted. Put <p> </p> tags around your paragraphs. Put <code> </code> tags around your code and data!
  • Titles consisting of a single word are discouraged, and in most cases are disallowed outright.
  • Read Where should I post X? if you're not absolutely sure you're posting in the right place.
  • Please read these before you post! —
  • Posts may use any of the Perl Monks Approved HTML tags:
    a, abbr, b, big, blockquote, br, caption, center, col, colgroup, dd, del, details, div, dl, dt, em, font, h1, h2, h3, h4, h5, h6, hr, i, ins, li, ol, p, pre, readmore, small, span, spoiler, strike, strong, sub, summary, sup, table, tbody, td, tfoot, th, thead, tr, tt, u, ul, wbr
  • You may need to use entities for some characters, as follows. (Exception: Within code tags, you can put the characters literally.)
            For:     Use:
    & &amp;
    < &lt;
    > &gt;
    [ &#91;
    ] &#93;
  • Link using PerlMonks shortcuts! What shortcuts can I use for linking?
  • See Writeup Formatting Tips and other pages linked from there for more info.