PATH=/usr/bin:/bin:/Users/fly2000/cpanfly-5.20/var/megalib/bin Start 2016-06-29T10:08:41 ActivePerl-2000 CPAN-2.10 Reading '/Users/fly2000/cpanfly-5.20/var/cpan/Metadata' Database was generated on Wed, 29 Jun 2016 05:53:43 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9739.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /Users/fly2000/cpanfly-5.20/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9739.tar.gz ok Time-HiRes-1.9739/ Time-HiRes-1.9739/Changes Time-HiRes-1.9739/fallback/ Time-HiRes-1.9739/hints/ Time-HiRes-1.9739/HiRes.pm Time-HiRes-1.9739/HiRes.xs Time-HiRes-1.9739/Makefile.PL Time-HiRes-1.9739/MANIFEST Time-HiRes-1.9739/META.json Time-HiRes-1.9739/META.yml Time-HiRes-1.9739/ppport.h Time-HiRes-1.9739/README Time-HiRes-1.9739/t/ Time-HiRes-1.9739/TODO Time-HiRes-1.9739/typemap Time-HiRes-1.9739/t/alarm.t Time-HiRes-1.9739/t/clock.t Time-HiRes-1.9739/t/gettimeofday.t Time-HiRes-1.9739/t/itimer.t Time-HiRes-1.9739/t/nanosleep.t Time-HiRes-1.9739/t/sleep.t Time-HiRes-1.9739/t/stat.t Time-HiRes-1.9739/t/time.t Time-HiRes-1.9739/t/tv_interval.t Time-HiRes-1.9739/t/ualarm.t Time-HiRes-1.9739/t/usleep.t Time-HiRes-1.9739/t/utime.t Time-HiRes-1.9739/t/Watchdog.pm Time-HiRes-1.9739/hints/aix.pl Time-HiRes-1.9739/hints/dec_osf.pl Time-HiRes-1.9739/hints/dynixptx.pl Time-HiRes-1.9739/hints/irix.pl Time-HiRes-1.9739/hints/linux.pl Time-HiRes-1.9739/hints/sco.pl Time-HiRes-1.9739/hints/solaris.pl Time-HiRes-1.9739/hints/svr4.pl Time-HiRes-1.9739/fallback/const-c.inc Time-HiRes-1.9739/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9739.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 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.9739.tar.gz /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9739.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.9739\" -DXS_VERSION=\"1.9739\" "-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.9739.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 98434, starting the watchdog process... # The watchdog process 98435 launched, continuing testing... # I am the watchdog process 98435, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300152 # Select returned! 4 0.300212 # 0.899929333333333 # Tick! 3 0.60028 # Select returned! 3 0.600321 # 0.799893 # Tick! 2 0.900399 # Select returned! 2 0.900434 # 0.699855333333333 # Tick! 1 1.200494 # Select returned! 1 1.20053 # 0.599823333333333 # Tick! 0 1.500577 # Select returned! 0 1.500613 # 0.499795666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1467220134.51447, t0 = 1467220134.51439, dt = 8.10623168945312e-05 # N = 2048, t1 = 1467220134.51467, t0 = 1467220134.5145, dt = 0.000169038772583008 # N = 4096, t1 = 1467220134.51499, t0 = 1467220134.51468, dt = 0.000313997268676758 # N = 8192, t1 = 1467220134.51563, t0 = 1467220134.515, dt = 0.000626087188720703 # N = 16384, t1 = 1467220134.51689, t0 = 1467220134.51564, dt = 0.00125002861022949 # N = 32768, t1 = 1467220134.51938, t0 = 1467220134.51689, dt = 0.00248885154724121 # N = 65536, t1 = 1467220134.52528, t0 = 1467220134.51939, dt = 0.00588893890380859 # N = 131072, t1 = 1467220134.53535, t0 = 1467220134.52539, dt = 0.00996112823486328 # N = 262144, t1 = 1467220134.5562, t0 = 1467220134.53545, dt = 0.0207469463348389 # Alarm 1 - 1467220134.56628 # Alarm 2 - 1467220134.60026 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 98434, terminating the watchdog process 98435 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 98435 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 98436, starting the watchdog process... # I am the watchdog process 98437, sleeping for 360 seconds... # The watchdog process 98437 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 = 1467220140.38786, t0 = 1467220138.88781 # dt = 1.50005030632019, rt = 3.35375467936938e-05 ok 2 ok 3 ok 4 # clock = 0.043244 # clock = 0.043244 0.105891 # clock = 0.043244 0.105891 0.168562 # clock = 0.043244 0.105891 0.168562 0.231249 ok 5 # I am the main process 98436, terminating the watchdog process 98437 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 98437 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 98438, starting the watchdog process... # I am the watchdog process 98439, sleeping for 360 seconds... # The watchdog process 98439 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 98438, terminating the watchdog process 98439 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98439 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 98440, starting the watchdog process... # I am the watchdog process 98441, sleeping for 360 seconds... # The watchdog process 98441 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497536 0.4 # Tick! 2 0.51693 # Tick! 1 0.930537 # Tick! 0 1.344398 # Tick! 0 1.758814 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 98440, terminating the watchdog process 98441 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98441 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 98442, starting the watchdog process... # I am the watchdog process 98443, sleeping for 360 seconds... # The watchdog process 98443 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 98442, terminating the watchdog process 98443 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98443 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 98444, starting the watchdog process... # I am the watchdog process 98445, sleeping for 360 seconds... # The watchdog process 98445 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1467220145.55489 ok 3 # sleep...0.500025 ok 4 # I am the main process 98444, terminating the watchdog process 98445 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98445 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 98446, starting the watchdog process... # I am the watchdog process 98447, sleeping for 360 seconds... # The watchdog process 98447 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 = 1467220146 1467220146 1467220146 1467220147 1467220147 # atime = 1467220146 1467220146 1467220147 1467220147 1467220147 # 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 98446, terminating the watchdog process 98447 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98447 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 98448, starting the watchdog process... # The watchdog process 98449 launched, continuing testing... # I am the watchdog process 98449, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 74.0614273548126, n = 100, s/n = 0.740614273548126 # I am the main process 98448, terminating the watchdog process 98449 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 98449 = 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 98451, starting the watchdog process... # I am the watchdog process 98452, sleeping for 360 seconds... # The watchdog process 98452 launched, continuing testing... ok 1 # tick = 1, one = 1467220147, two = 1467220147, three = 1467220147 ok 2 # tick = 3, one = 1467220147, two = 1467220147, three = 1467220147 ok 3 - negative time error # t0 = 1467220147.88718 # ualarm(100000) # t1 = 1467220147.98728 # dt = 0.100105047225952 # r = 1.00105047225952 ok 4 # t0 = 1467220147.98752 # ualarm(1100000) # t1 = 1467220149.08762 # dt = 1.10009694099426 # r = 1.0000881281766 ok 5 # t0 = 1467220149.08843 # ualarm(2200000) # t1 = 1467220151.28877 # dt = 2.20034790039062 # r = 1.00015813654119 ok 6 # t0 = 1467220151.28908 # ualarm(4300000) # t1 = 1467220155.58912 # dt = 4.30004000663757 # r = 1.0000093038692 ok 7 # t0 = 1467220155.5894 # got0 = 0 # t1 = 1467220155.8894 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 98451, terminating the watchdog process 98452 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 98452 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 98453, starting the watchdog process... # I am the watchdog process 98456, sleeping for 360 seconds... # The watchdog process 98456 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 98453, terminating the watchdog process 98456 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 98456 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 28 wallclock secs ( 0.09 usr 0.04 sys + 14.47 cusr 0.17 csys = 14.77 CPU) Result: PASS JHI/Time-HiRes-1.9739.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.9739-6nOiaG && tar cvf - Time-HiRes-1.9739.ppd blib) | gzip -c >/Users/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI/Time-HiRes-1.9739.tar.gz Time-HiRes-1.9739.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-29T10:09:20