PATH=/usr/bin:/bin:/Users/fly2000/cpanfly-5.20/var/megalib/bin Start 2017-04-17T10:16:20 ActivePerl-2000 CPAN-2.10 Reading '/Users/fly2000/cpanfly-5.20/var/cpan/Metadata' Database was generated on Mon, 17 Apr 2017 04:56:20 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9742.tar.gz Checksum for /Users/fly2000/cpanfly-5.20/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9742.tar.gz ok Time-HiRes-1.9742/ Time-HiRes-1.9742/Changes Time-HiRes-1.9742/fallback/ Time-HiRes-1.9742/hints/ Time-HiRes-1.9742/HiRes.pm Time-HiRes-1.9742/HiRes.xs Time-HiRes-1.9742/Makefile.PL Time-HiRes-1.9742/MANIFEST Time-HiRes-1.9742/META.json Time-HiRes-1.9742/META.yml Time-HiRes-1.9742/ppport.h Time-HiRes-1.9742/README Time-HiRes-1.9742/t/ Time-HiRes-1.9742/TODO Time-HiRes-1.9742/typemap Time-HiRes-1.9742/t/alarm.t Time-HiRes-1.9742/t/clock.t Time-HiRes-1.9742/t/gettimeofday.t Time-HiRes-1.9742/t/itimer.t Time-HiRes-1.9742/t/nanosleep.t Time-HiRes-1.9742/t/sleep.t Time-HiRes-1.9742/t/stat.t Time-HiRes-1.9742/t/time.t Time-HiRes-1.9742/t/tv_interval.t Time-HiRes-1.9742/t/ualarm.t Time-HiRes-1.9742/t/usleep.t Time-HiRes-1.9742/t/utime.t Time-HiRes-1.9742/t/Watchdog.pm Time-HiRes-1.9742/hints/aix.pl Time-HiRes-1.9742/hints/dec_osf.pl Time-HiRes-1.9742/hints/dynixptx.pl Time-HiRes-1.9742/hints/irix.pl Time-HiRes-1.9742/hints/linux.pl Time-HiRes-1.9742/hints/sco.pl Time-HiRes-1.9742/hints/solaris.pl Time-HiRes-1.9742/hints/svr4.pl Time-HiRes-1.9742/fallback/const-c.inc Time-HiRes-1.9742/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9742.tar.gz with Makefile.PL >>> /Users/fly2000/ap2000-298465/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 clockid_t... NOT found, will use int. 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 futimens()... NOT found. Looking for utimensat()... NOT 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/fly2000/cpanfly-5.20/var/megalib Want /Users/fly2000/ap2000-298465/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.9742.tar.gz /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9742.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/Users/fly2000/ap2000-298465/bin/perl-dynamic" "/Users/fly2000/cpanfly-5.20/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly2000/ap2000-298465/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-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9742\" -DXS_VERSION=\"1.9742\" "-I/Users/fly2000/ap2000-298465/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETTIME_EMULATION -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_GETRES_EMULATION -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK_NANOSLEEP_EMULATION -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT_ST_XTIMESPEC -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 x86_64 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly2000/ap2000-298465/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.9742.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/fly2000/ap2000-298465/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 70733, starting the watchdog process... # The watchdog process 70734 launched, continuing testing... # I am the watchdog process 70734, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300242 # Select returned! 4 0.300329 # 0.899890333333333 # Tick! 3 0.600545 # Select returned! 3 0.600612 # 0.799796 # Tick! 2 0.900847 # Select returned! 2 0.900925 # 0.699691666666667 # Tick! 1 1.201115 # Select returned! 1 1.201185 # 0.599605 # Tick! 0 1.501429 # Select returned! 0 1.501508 # 0.499497333333333 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1492449390.59914, t0 = 1492449390.59903, dt = 0.000116109848022461 # N = 2048, t1 = 1492449390.59939, t0 = 1492449390.59921, dt = 0.00018310546875 # N = 4096, t1 = 1492449390.59976, t0 = 1492449390.5994, dt = 0.000358104705810547 # N = 8192, t1 = 1492449390.60047, t0 = 1492449390.59977, dt = 0.000698089599609375 # N = 16384, t1 = 1492449390.60173, t0 = 1492449390.60048, dt = 0.00125503540039062 # N = 32768, t1 = 1492449390.60423, t0 = 1492449390.60174, dt = 0.00249099731445312 # N = 65536, t1 = 1492449390.60909, t0 = 1492449390.60425, dt = 0.00483989715576172 # N = 131072, t1 = 1492449390.61798, t0 = 1492449390.6091, dt = 0.00888705253601074 # N = 262144, t1 = 1492449390.63566, t0 = 1492449390.61799, dt = 0.0176668167114258 # Alarm 1 - 1492449390.64574 # Alarm 2 - 1492449390.6795 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 70733, terminating the watchdog process 70734 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 70734 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 70735, starting the watchdog process... # The watchdog process 70736 launched, continuing testing... # I am the watchdog process 70736, 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 = 1492449396.4238, t0 = 1492449394.92353 # dt = 1.50026965141296, rt = 0.000179767608642578 ok 2 ok 3 ok 4 # clock = 0.043839 # clock = 0.043839 0.109605 # clock = 0.043839 0.109605 0.17317 # clock = 0.043839 0.109605 0.17317 0.236755 ok 5 # I am the main process 70735, terminating the watchdog process 70736 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 70736 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 70740, starting the watchdog process... # The watchdog process 70741 launched, continuing testing... # I am the watchdog process 70741, 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 70740, terminating the watchdog process 70741 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70741 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 70742, starting the watchdog process... # The watchdog process 70743 launched, continuing testing... # I am the watchdog process 70743, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497561 0.4 # Tick! 2 0.509605 # Tick! 1 0.916583 # Tick! 0 1.323466 # Tick! 0 1.730494 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 70742, terminating the watchdog process 70743 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70743 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 70744, starting the watchdog process... # The watchdog process 70745 launched, continuing testing... # I am the watchdog process 70745, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 70744, terminating the watchdog process 70745 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70745 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 70746, starting the watchdog process... # The watchdog process 70747 launched, continuing testing... # I am the watchdog process 70747, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1492449401.56246 ok 3 # sleep...0.500153 ok 4 # I am the main process 70746, terminating the watchdog process 70747 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70747 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 70748, starting the watchdog process... # The watchdog process 70749 launched, continuing testing... # I am the watchdog process 70749, 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 = 1492449402 1492449402 1492449402 1492449403 1492449403 # atime = 1492449402 1492449402 1492449402 1492449403 1492449403 # 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 70748, terminating the watchdog process 70749 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 70749 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 70750, starting the watchdog process... # The watchdog process 70751 launched, continuing testing... # I am the watchdog process 70751, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 61.8742542266846, n = 100, s/n = 0.618742542266846 # I am the main process 70750, terminating the watchdog process 70751 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 70751 = 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 70753, starting the watchdog process... # The watchdog process 70754 launched, continuing testing... # I am the watchdog process 70754, sleeping for 360 seconds... ok 1 # tick = 1, one = 1492449403, two = 1492449403, three = 1492449403 ok 2 # tick = 3, one = 1492449403, two = 1492449403, three = 1492449403 ok 3 - negative time error # t0 = 1492449403.76117 # ualarm(100000) # t1 = 1492449403.86127 # dt = 0.100100994110107 # r = 1.00100994110107 ok 4 # t0 = 1492449403.86157 # ualarm(1100000) # t1 = 1492449404.96163 # dt = 1.10005903244019 # r = 1.00005366585471 ok 5 # t0 = 1492449404.96193 # ualarm(2200000) # t1 = 1492449407.16196 # dt = 2.20003604888916 # r = 1.00001638585871 ok 6 # t0 = 1492449407.16227 # ualarm(4300000) # t1 = 1492449411.46223 # dt = 4.29996705055237 # r = 0.99999233733776 ok 7 # t0 = 1492449411.46253 # got0 = 0 # t1 = 1492449411.76253 # t1 - t0 = 0.300000905990601 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 70753, terminating the watchdog process 70754 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 70754 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 70755, starting the watchdog process... # The watchdog process 70758 launched, continuing testing... # I am the watchdog process 70758, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 70755, terminating the watchdog process 70758 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 70758 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 26 wallclock secs ( 0.10 usr 0.04 sys + 14.68 cusr 0.16 csys = 14.98 CPU) Result: PASS JHI/Time-HiRes-1.9742.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2000/cpanfly-5.20/var/tmp/cpan_build/Time-HiRes-1.9742-6t_Xsj && tar cvf - Time-HiRes-1.9742.ppd blib) | gzip -c >/Users/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI/Time-HiRes-1.9742.tar.gz Time-HiRes-1.9742.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 2017-04-17T10:16:56