PATH=/usr/bin:/bin:/Users/fly1400/cpanfly-5.14/var/megalib/bin Start 2016-04-25T01:54:04 ActivePerl-1400 CPAN-2.10 Reading '/Users/fly1400/cpanfly-5.14/var/cpan/Metadata' Database was generated on Sun, 24 Apr 2016 04:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /Users/fly1400/cpanfly-5.14/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz ok Time-HiRes-1.9733/ Time-HiRes-1.9733/Changes Time-HiRes-1.9733/fallback/ Time-HiRes-1.9733/hints/ Time-HiRes-1.9733/HiRes.pm Time-HiRes-1.9733/HiRes.xs Time-HiRes-1.9733/Makefile.PL Time-HiRes-1.9733/MANIFEST Time-HiRes-1.9733/META.json Time-HiRes-1.9733/META.yml Time-HiRes-1.9733/ppport.h Time-HiRes-1.9733/README Time-HiRes-1.9733/t/ Time-HiRes-1.9733/TODO Time-HiRes-1.9733/typemap Time-HiRes-1.9733/t/alarm.t Time-HiRes-1.9733/t/clock.t Time-HiRes-1.9733/t/gettimeofday.t Time-HiRes-1.9733/t/itimer.t Time-HiRes-1.9733/t/nanosleep.t Time-HiRes-1.9733/t/sleep.t Time-HiRes-1.9733/t/stat.t Time-HiRes-1.9733/t/time.t Time-HiRes-1.9733/t/tv_interval.t Time-HiRes-1.9733/t/ualarm.t Time-HiRes-1.9733/t/usleep.t Time-HiRes-1.9733/t/Watchdog.pm Time-HiRes-1.9733/hints/aix.pl Time-HiRes-1.9733/hints/dec_osf.pl Time-HiRes-1.9733/hints/dynixptx.pl Time-HiRes-1.9733/hints/irix.pl Time-HiRes-1.9733/hints/linux.pl Time-HiRes-1.9733/hints/sco.pl Time-HiRes-1.9733/hints/solaris.pl Time-HiRes-1.9733/hints/svr4.pl Time-HiRes-1.9733/fallback/const-c.inc Time-HiRes-1.9733/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9733.tar.gz with Makefile.PL >>> /Users/fly1400/ap1400/bin/perl Makefile.PL Configuring Time::HiRes... Have syscall()... looking for syscall.h... found . Looking for gettimeofday()... found. Looking for setitimer()... found. Looking for getitimer()... found. You have interval timers (both setitimer and getitimer). Looking for ualarm()... found. Looking for usleep()... found. Looking for nanosleep()... testing... found. You can mix subsecond sleeps with signals, if you want to. (It's still not portable, though.) Looking for clock_gettime()... found (via emulation). Looking for clock_getres()... found (via emulation). Looking for clock_nanosleep()... found (via emulation). Looking for clock()... found. Looking for stat() subsecond timestamps... Trying struct stat st_atimespec.tv_nsec...found. Trying struct stat st_atimensec...NOT found. Trying struct stat st_atime_n...NOT found. Trying struct stat st_atim.tv_nsec...NOT found. Trying struct stat st_uatime...NOT found. You seem to have stat() subsecond timestamps. (Your struct stat has them, but the filesystems must help.) Checking if your kit is complete... Looks good Generating a Unix-style Makefile Writing Makefile for Time::HiRes Writing MYMETA.yml and MYMETA.json Now you may issue 'make'. Do not forget also 'make test'. JHI/Time-HiRes-1.9733.tar.gz /Users/fly1400/ap1400/bin/perl Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9733.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/Users/fly1400/ap1400/bin/perl" "/Users/fly1400/cpanfly-5.14/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly1400/ap1400/lib/ExtUtils/typemap" -typemap "typemap" HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -arch i386 -arch x86_64 -nostdinc -B/Developer/SDKs/MacOSX10.5.sdk/usr/include/gcc -B/Developer/SDKs/MacOSX10.5.sdk/usr/lib/gcc -isystem/Developer/SDKs/MacOSX10.5.sdk/usr/include -F/Developer/SDKs/MacOSX10.5.sdk/System/Library/Frameworks -mmacosx-version-min=10.5 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9733\" -DXS_VERSION=\"1.9733\" "-I/Users/fly1400/ap1400/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT=1 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.bundle cc -mmacosx-version-min=10.5 -bundle -undefined dynamic_lookup -Wl,-search_paths_first -arch i386 -arch x86_64 -Wl,-syslibroot,/Developer/SDKs/MacOSX10.5.sdk -mmacosx-version-min=10.5 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly1400/ap1400/bin/perl" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Manifying 1 pod document JHI/Time-HiRes-1.9733.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" PERL_DL_NONLAZY=1 "/Users/fly1400/ap1400/bin/perl" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/alarm.t ......... 1..10 # I am the main process 31512, starting the watchdog process... # I am the watchdog process 31513, sleeping for 360 seconds... # The watchdog process 31513 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.407782 # Select returned! 4 0.40791 # 0.86403 # Tick! 3 0.708035 # Select returned! 3 0.708147 # 0.763951 # Tick! 2 1.008262 # Select returned! 2 1.008362 # 0.663879333333333 # Tick! 1 1.308463 # Select returned! 1 1.308568 # 0.563810666666667 # Tick! 0 1.608686 # Select returned! 0 1.608793 # 0.463735666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1461574480.50645, t0 = 1461574480.50638, dt = 6.98566436767578e-05 # N = 2048, t1 = 1461574480.50664, t0 = 1461574480.50651, dt = 0.000138044357299805 # N = 4096, t1 = 1461574480.50694, t0 = 1461574480.50668, dt = 0.000267982482910156 # N = 8192, t1 = 1461574480.50752, t0 = 1461574480.50698, dt = 0.000541210174560547 # N = 16384, t1 = 1461574480.50863, t0 = 1461574480.50755, dt = 0.00108098983764648 # N = 32768, t1 = 1461574480.5108, t0 = 1461574480.50866, dt = 0.00214505195617676 # N = 65536, t1 = 1461574480.51515, t0 = 1461574480.51083, dt = 0.0043189525604248 # N = 131072, t1 = 1461574480.52493, t0 = 1461574480.51518, dt = 0.00974798202514648 # N = 262144, t1 = 1461574480.54287, t0 = 1461574480.52508, dt = 0.0177931785583496 # Alarm 1 - 1461574480.55318 # Alarm 2 - 1461574480.59055 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 31512, terminating the watchdog process 31513 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 31513 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 31514, starting the watchdog process... # I am the watchdog process 31515, sleeping for 360 seconds... # The watchdog process 31515 launched, continuing testing... ok 1 - require Time::HiRes; # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # CLOCK_REALTIME: try = 1 # t1 = 1461574486.39274, t0 = 1461574484.89269 # dt = 1.50004768371582, rt = 3.17891438801343e-05 ok 2 ok 3 ok 4 # clock = 0.041421 # clock = 0.041421 0.106573 # clock = 0.041421 0.106573 0.17196 # clock = 0.041421 0.106573 0.17196 0.237395 ok 5 # I am the main process 31514, terminating the watchdog process 31515 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 31515 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 31516, starting the watchdog process... # The watchdog process 31517 launched, continuing testing... # gettimeofday returned 2 args # I am the watchdog process 31517, sleeping for 360 seconds... ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 31516, terminating the watchdog process 31517 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 31517 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 31518, starting the watchdog process... # The watchdog process 31519 launched, continuing testing... # I am the watchdog process 31519, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.498411 0.4 # Tick! 2 0.554147 # Tick! 1 1.015571 # Tick! 0 1.449236 # Tick! 0 1.888053 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 31518, terminating the watchdog process 31519 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 31519 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 31520, starting the watchdog process... # The watchdog process 31521 launched, continuing testing... # I am the watchdog process 31521, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 31520, terminating the watchdog process 31521 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 31521 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 31522, starting the watchdog process... # The watchdog process 31523 launched, continuing testing... # I am the watchdog process 31523, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1461574492.01696 ok 3 # sleep...0.500034 ok 4 # I am the main process 31522, terminating the watchdog process 31523 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 31523 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 31524, starting the watchdog process... # I am the watchdog process 31525, sleeping for 360 seconds... # The watchdog process 31525 launched, continuing testing... ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 ok 11 ok 12 ok 13 ok 14 ok 15 ok 16 ok 17 ok 18 ok 19 ok 20 ok 21 ok 22 ok 23 ok 24 ok 25 ok 26 ok 27 ok 28 ok 29 ok 30 ok 31 ok 32 ok 33 ok 34 ok 35 # mtime = 1461574493 1461574493 1461574493 1461574494 1461574494 # atime = 1461574493 1461574493 1461574493 1461574494 1461574494 # ai = 4, mi = 4, ss = 0 ok 36 # skip no subsecond timestamps detected ok 37 ok 38 ok 39 ok 40 ok 41 ok 42 ok 43 # I am the main process 31524, terminating the watchdog process 31525 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 31525 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 31526, starting the watchdog process... # I am the watchdog process 31527, sleeping for 360 seconds... # The watchdog process 31527 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 67.0756652355194, n = 100, s/n = 0.670756652355194 # I am the main process 31526, terminating the watchdog process 31527 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 31527 = 1 # All done. ok t/tv_interval.t ... 1..2 ok 1 - require Time::HiRes; ok 2 ok t/ualarm.t ........ 1..12 # I am the main process 31529, starting the watchdog process... # The watchdog process 31530 launched, continuing testing... # I am the watchdog process 31530, sleeping for 360 seconds... ok 1 # tick = 1, one = 1461574494, two = 1461574494, three = 1461574494 ok 2 # tick = 3, one = 1461574494, two = 1461574494, three = 1461574494 ok 3 - negative time error # t0 = 1461574494.82435 # ualarm(100000) # t1 = 1461574494.92446 # dt = 0.100106954574585 # r = 1.00106954574585 ok 4 # t0 = 1461574494.92486 # ualarm(1100000) # t1 = 1461574496.02502 # dt = 1.10016703605652 # r = 1.00015185096047 ok 5 # t0 = 1461574496.0254 # ualarm(2200000) # t1 = 1461574498.22558 # dt = 2.20018100738525 # r = 1.00008227608421 ok 6 # t0 = 1461574498.22621 # ualarm(4300000) # t1 = 1461574502.52625 # dt = 4.30004405975342 # r = 1.00001024645428 ok 7 # t0 = 1461574502.52716 # got0 = 0 # t1 = 1461574502.82716 # t1 - t0 = 0.300000905990601 # got1 = 199998 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 31529, terminating the watchdog process 31530 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 31530 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 31531, starting the watchdog process... # The watchdog process 31534 launched, continuing testing... # I am the watchdog process 31534, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 31531, terminating the watchdog process 31534 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 31534 = 1 # All done. ok All tests successful. Files=11, Tests=95, 29 wallclock secs ( 0.09 usr 0.04 sys + 13.86 cusr 0.20 csys = 14.19 CPU) Result: PASS JHI/Time-HiRes-1.9733.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly1400/cpanfly-5.14/var/cpan/build/Time-HiRes-1.9733-gIJNme && tar cvf - Time-HiRes-1.9733.ppd blib) | gzip -c >/Users/fly1400/cpanfly-5.14/var/REPO/J/JH/JHI/Time-HiRes-1.9733.tar.gz Time-HiRes-1.9733.ppd blib/ blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.bundle blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/man3/ blib/man3/Time::HiRes.3 >>> mv /Users/fly1400/cpanfly-5.14/var/cpan/build/Time-HiRes-1.9733-gIJNme/Time-HiRes-1.9733.ppd /Users/fly1400/cpanfly-5.14/var/REPO/J/JH/JHI Finished 2016-04-25T01:55:13