PATH=/usr/bin:/bin:/Users/fly2200/cpanfly-5.22/var/megalib/bin Start 2016-04-24T06:16:05 ActivePerl-2200 CPAN-2.10 Reading '/Users/fly2200/cpanfly-5.22/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/fly2200/cpanfly-5.22/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/fly2200/ap2200-299195/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 Have /Users/fly2200/cpanfly-5.22/var/megalib Want /Users/fly2200/ap2200-299195/lib Your perl and your Config.pm seem to have different ideas about the architecture they are running on. Perl thinks: [megalib] Config says: [darwin-thread-multi-2level] This may or may not cause problems. Please check your installation of perl if you have problems building this extension. 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/fly2200/ap2200-299195/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 Time::HiRes () chmod 644 "HiRes.bs" "/Users/fly2200/ap2200-299195/bin/perl-dynamic" "/Users/fly2200/cpanfly-5.22/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly2200/ap2200-299195/lib/ExtUtils/typemap" -typemap "typemap" HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -arch x86_64 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9733\" -DXS_VERSION=\"1.9733\" "-I/Users/fly2200/ap2200-299195/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 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly2200/ap2200-299195/bin/perl-dynamic" -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/fly2200/ap2200-299195/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 83744, starting the watchdog process... # The watchdog process 83745 launched, continuing testing... # I am the watchdog process 83745, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.304474 # Select returned! 4 0.304669 # 0.898443666666667 # Tick! 3 0.608906 # Select returned! 3 0.609044 # 0.796985333333333 # Tick! 2 0.912801 # Select returned! 2 0.913082 # 0.695639333333333 # Tick! 1 1.217366 # Select returned! 1 1.217546 # 0.594151333333333 # Tick! 0 1.521782 # Select returned! 0 1.521986 # 0.492671333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1461503773.48353, t0 = 1461503773.48347, dt = 5.60283660888672e-05 # N = 2048, t1 = 1461503773.48371, t0 = 1461503773.4836, dt = 0.000108957290649414 # N = 4096, t1 = 1461503773.48399, t0 = 1461503773.48377, dt = 0.000216960906982422 # N = 8192, t1 = 1461503773.48449, t0 = 1461503773.48406, dt = 0.000432014465332031 # N = 16384, t1 = 1461503773.48544, t0 = 1461503773.48455, dt = 0.000891923904418945 # N = 32768, t1 = 1461503773.48692, t0 = 1461503773.48551, dt = 0.00141096115112305 # N = 65536, t1 = 1461503773.4898, t0 = 1461503773.48697, dt = 0.00283193588256836 # N = 131072, t1 = 1461503773.49515, t0 = 1461503773.48996, dt = 0.00519204139709473 # N = 262144, t1 = 1461503773.50511, t0 = 1461503773.49521, dt = 0.00989890098571777 # N = 524288, t1 = 1461503773.52474, t0 = 1461503773.5052, dt = 0.0195457935333252 # Alarm 1 - 1461503773.53698 # Alarm 2 - 1461503773.58259 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 83744, terminating the watchdog process 83745 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 83745 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 83749, starting the watchdog process... # The watchdog process 83750 launched, continuing testing... # I am the watchdog process 83750, 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 = 1461503779.36238, t0 = 1461503777.85405 # dt = 1.50833106040955, rt = 0.00555404027303052 ok 2 ok 3 ok 4 # clock = 0.035149 # clock = 0.035149 0.073452 # clock = 0.035149 0.073452 0.11065 # clock = 0.035149 0.073452 0.11065 0.147966 ok 5 # I am the main process 83749, terminating the watchdog process 83750 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 83750 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 83751, starting the watchdog process... # The watchdog process 83752 launched, continuing testing... # I am the watchdog process 83752, 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 83751, terminating the watchdog process 83752 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 83752 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 83753, starting the watchdog process... # The watchdog process 83754 launched, continuing testing... # I am the watchdog process 83754, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497963 0.4 # Tick! 2 0.512149 # Tick! 1 0.921041 # Tick! 0 1.329064 # Tick! 0 1.738647 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 83753, terminating the watchdog process 83754 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 83754 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 83755, starting the watchdog process... # The watchdog process 83756 launched, continuing testing... # I am the watchdog process 83756, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 83755, terminating the watchdog process 83756 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 83756 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 83757, starting the watchdog process... # The watchdog process 83758 launched, continuing testing... # I am the watchdog process 83758, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1461503784.40355 ok 3 # sleep...0.504309 ok 4 # I am the main process 83757, terminating the watchdog process 83758 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 83758 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 83759, starting the watchdog process... # The watchdog process 83760 launched, continuing testing... # I am the watchdog process 83760, 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 = 1461503785 1461503785 1461503785 1461503785 1461503786 # atime = 1461503785 1461503785 1461503785 1461503786 1461503786 # 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 83759, terminating the watchdog process 83760 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 83760 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 83761, starting the watchdog process... # The watchdog process 83762 launched, continuing testing... # I am the watchdog process 83762, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 43.5779492855072, n = 100, s/n = 0.435779492855072 # I am the main process 83761, terminating the watchdog process 83762 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 83762 = 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 83764, starting the watchdog process... # The watchdog process 83765 launched, continuing testing... # I am the watchdog process 83765, sleeping for 360 seconds... ok 1 # tick = 1, one = 1461503786, two = 1461503786, three = 1461503786 ok 2 # tick = 3, one = 1461503786, two = 1461503786, three = 1461503786 ok 3 - negative time error # t0 = 1461503786.57376 # ualarm(100000) # t1 = 1461503786.67602 # dt = 0.10226583480835 # r = 1.0226583480835 ok 4 # t0 = 1461503786.67636 # ualarm(1100000) # t1 = 1461503787.77759 # dt = 1.10123610496521 # r = 1.00112373178655 ok 5 # t0 = 1461503787.77793 # ualarm(2200000) # t1 = 1461503789.98009 # dt = 2.20215821266174 # r = 1.00098100575534 ok 6 # t0 = 1461503789.98049 # ualarm(4300000) # t1 = 1461503794.28373 # dt = 4.30323791503906 # r = 1.00075300349746 ok 7 # t0 = 1461503794.28408 # got0 = 0 # t1 = 1461503794.58408 # t1 - t0 = 0.300000905990601 # got1 = 199994 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 83764, terminating the watchdog process 83765 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 83765 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 83766, starting the watchdog process... # The watchdog process 83767 launched, continuing testing... # I am the watchdog process 83767, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 83766, terminating the watchdog process 83767 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 83767 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.07 usr 0.03 sys + 14.37 cusr 0.21 csys = 14.68 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/fly2200/cpanfly-5.22/var/cpan/build/Time-HiRes-1.9733-w88pOw && tar cvf - Time-HiRes-1.9733.ppd blib) | gzip -c >/Users/fly2200/cpanfly-5.22/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/fly2200/cpanfly-5.22/var/cpan/build/Time-HiRes-1.9733-w88pOw/Time-HiRes-1.9733.ppd /Users/fly2200/cpanfly-5.22/var/REPO/J/JH/JHI Finished 2016-04-24T06:16:38