PATH=/Users/fly2400/cpanfly-5.24/bin:/Users/fly2400/ap2400-300558/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/bin:/opt/X11/bin:/Users/fly2400/cpanfly-5.24/var/megalib/bin Start 2016-06-12T14:36:09 ActivePerl-2400 CPAN-2.10 Reading '/Users/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Sun, 12 Jun 2016 05:53:44 GMT Checksum for /Users/fly2400/cpanfly-5.24/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/fly2400/ap2400-300558/bin/perl-dynamic 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/fly2400/ap2400-300558/bin/perl-dynamic 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 HiRes () chmod 644 "HiRes.bs" "/Users/fly2400/ap2400-300558/bin/perl-dynamic" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 "/Users/fly2400/ap2400-300558/bin/perl-dynamic" "/Users/fly2400/ap2400-300558/lib/ExtUtils/xsubpp" -typemap '/Users/fly2400/ap2400-300558/lib/ExtUtils/typemap' -typemap '/Users/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9733-UTEiUT/typemap' HiRes.xs > HiRes.xsc mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -mmacosx-version-min=10.9 -arch x86_64 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-strict-aliasing -pipe -fstack-protector -DPERL_USE_SAFE_PUTENV -O3 -DVERSION=\"1.9733\" -DXS_VERSION=\"1.9733\" "-I/Users/fly2400/ap2400-300558/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 gcc -mmacosx-version-min=10.9 -bundle -undefined dynamic_lookup -Wl,-search_paths_first -arch x86_64 -fstack-protector -o blib/arch/auto/Time/HiRes/HiRes.bundle HiRes.o \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle Manifying 1 pod document JHI/Time-HiRes-1.9733.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 "/Users/fly2400/ap2400-300558/bin/perl-dynamic" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 PERL_DL_NONLAZY=1 "/Users/fly2400/ap2400-300558/bin/perl-dynamic" "-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 70406, starting the watchdog process... # The watchdog process 70407 launched, continuing testing... # I am the watchdog process 70407, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.305035 # Select returned! 4 0.305371 # 0.898209666666667 # Tick! 3 0.609873 # Select returned! 3 0.610168 # 0.796610666666667 # Tick! 2 0.914566 # Select returned! 2 0.914847 # 0.695051 # Tick! 1 1.217997 # Select returned! 1 1.218359 # 0.593880333333333 # Tick! 0 1.521014 # Select returned! 0 1.521624 # 0.492792 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1465767378.12279, t0 = 1465767378.12272, dt = 6.69956207275391e-05 # N = 2048, t1 = 1465767378.12309, t0 = 1465767378.12296, dt = 0.000130891799926758 # N = 4096, t1 = 1465767378.12349, t0 = 1465767378.12324, dt = 0.000255823135375977 # N = 8192, t1 = 1465767378.12415, t0 = 1465767378.12364, dt = 0.000510931015014648 # N = 16384, t1 = 1465767378.12531, t0 = 1465767378.1243, dt = 0.00101518630981445 # N = 32768, t1 = 1465767378.12756, t0 = 1465767378.12548, dt = 0.00207114219665527 # N = 65536, t1 = 1465767378.13106, t0 = 1465767378.12784, dt = 0.00321698188781738 # N = 131072, t1 = 1465767378.13624, t0 = 1465767378.13134, dt = 0.00489306449890137 # N = 262144, t1 = 1465767378.14612, t0 = 1465767378.13664, dt = 0.00948190689086914 # N = 524288, t1 = 1465767378.16518, t0 = 1465767378.14641, dt = 0.0187761783599854 # Alarm 1 - 1465767378.17589 # Alarm 2 - 1465767378.21395 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 70406, terminating the watchdog process 70407 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 70407 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 70408, starting the watchdog process... # The watchdog process 70409 launched, continuing testing... # I am the watchdog process 70409, sleeping for 360 seconds... 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 = 1465767384.01846, t0 = 1465767382.51326 # dt = 1.50519609451294, rt = 0.00346406300862623 ok 2 ok 3 ok 4 # clock = 0.068006 # clock = 0.068006 0.110065 # clock = 0.068006 0.110065 0.146806 # clock = 0.068006 0.110065 0.146806 0.183235 ok 5 # I am the main process 70408, terminating the watchdog process 70409 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 70409 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 70410, starting the watchdog process... # The watchdog process 70411 launched, continuing testing... # I am the watchdog process 70411, sleeping for 360 seconds... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 70410, terminating the watchdog process 70411 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70411 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 70415, starting the watchdog process... # The watchdog process 70416 launched, continuing testing... # I am the watchdog process 70416, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.499736 0.4 # Tick! 2 0.512907 # Tick! 1 0.92276 # Tick! 0 1.333106 # Tick! 0 1.742726 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 70415, terminating the watchdog process 70416 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 70416 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 70417, starting the watchdog process... # The watchdog process 70418 launched, continuing testing... # I am the watchdog process 70418, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 70417, terminating the watchdog process 70418 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70418 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 70419, starting the watchdog process... # The watchdog process 70420 launched, continuing testing... # I am the watchdog process 70420, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1465767389.21416 ok 3 # sleep...0.504181 ok 4 # I am the main process 70419, terminating the watchdog process 70420 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 70420 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 70421, starting the watchdog process... # The watchdog process 70422 launched, continuing testing... # I am the watchdog process 70422, sleeping for 360 seconds... 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 = 1465767389 1465767390 1465767390 1465767390 1465767391 # atime = 1465767390 1465767390 1465767390 1465767391 1465767391 # 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 70421, terminating the watchdog process 70422 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 70422 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 70423, starting the watchdog process... # The watchdog process 70424 launched, continuing testing... # I am the watchdog process 70424, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 38.3147432804108, n = 100, s/n = 0.383147432804108 # I am the main process 70423, terminating the watchdog process 70424 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 70424 = 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 70426, starting the watchdog process... # The watchdog process 70427 launched, continuing testing... # I am the watchdog process 70427, sleeping for 360 seconds... ok 1 # tick = 1, one = 1465767391, two = 1465767391, three = 1465767391 ok 2 # tick = 3, one = 1465767391, two = 1465767391, three = 1465767391 ok 3 - negative time error # t0 = 1465767391.57562 # ualarm(100000) # t1 = 1465767391.67851 # dt = 0.102894067764282 # r = 1.02894067764282 ok 4 # t0 = 1465767391.6797 # ualarm(1100000) # t1 = 1465767392.78403 # dt = 1.104327917099 # r = 1.00393447009 ok 5 # t0 = 1465767392.78475 # ualarm(2200000) # t1 = 1465767394.98794 # dt = 2.20319104194641 # r = 1.001450473612 ok 6 # t0 = 1465767394.98864 # ualarm(4300000) # t1 = 1465767399.29206 # dt = 4.30342602729797 # r = 1.00079675053441 ok 7 # t0 = 1465767399.29271 # got0 = 0 # t1 = 1465767399.59271 # t1 - t0 = 0.300000905990601 # got1 = 199991 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 70426, terminating the watchdog process 70427 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 70427 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 70428, starting the watchdog process... # The watchdog process 70429 launched, continuing testing... # I am the watchdog process 70429, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 70428, terminating the watchdog process 70429 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 70429 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.07 usr 0.02 sys + 14.80 cusr 0.24 csys = 15.13 CPU) Result: PASS JHI/Time-HiRes-1.9733.tar.gz make test TEST_VERBOSE=1 -- OK Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9733-UTEiUT && tar cvf - Time-HiRes-1.9733.ppd blib) | gzip -c >/Users/fly2400/cpanfly-5.24/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 Finished 2016-06-12T14:36:44