PATH=/usr/bin:/bin:/Users/fly2000/cpanfly-5.20/var/megalib/bin Start 2016-03-14T10:06:55 ActivePerl-2000 CPAN-2.10 Reading '/Users/fly2000/cpanfly-5.20/var/cpan/Metadata' Database was generated on Mon, 14 Mar 2016 05:53:41 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9732.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.9732.tar.gz ok Time-HiRes-1.9732/ Time-HiRes-1.9732/Changes Time-HiRes-1.9732/fallback/ Time-HiRes-1.9732/hints/ Time-HiRes-1.9732/HiRes.pm Time-HiRes-1.9732/HiRes.xs Time-HiRes-1.9732/Makefile.PL Time-HiRes-1.9732/MANIFEST Time-HiRes-1.9732/META.json Time-HiRes-1.9732/META.yml Time-HiRes-1.9732/ppport.h Time-HiRes-1.9732/README Time-HiRes-1.9732/t/ Time-HiRes-1.9732/TODO Time-HiRes-1.9732/typemap Time-HiRes-1.9732/t/alarm.t Time-HiRes-1.9732/t/clock.t Time-HiRes-1.9732/t/gettimeofday.t Time-HiRes-1.9732/t/itimer.t Time-HiRes-1.9732/t/nanosleep.t Time-HiRes-1.9732/t/sleep.t Time-HiRes-1.9732/t/stat.t Time-HiRes-1.9732/t/time.t Time-HiRes-1.9732/t/tv_interval.t Time-HiRes-1.9732/t/ualarm.t Time-HiRes-1.9732/t/usleep.t Time-HiRes-1.9732/t/Watchdog.pm Time-HiRes-1.9732/hints/aix.pl Time-HiRes-1.9732/hints/dec_osf.pl Time-HiRes-1.9732/hints/dynixptx.pl Time-HiRes-1.9732/hints/irix.pl Time-HiRes-1.9732/hints/linux.pl Time-HiRes-1.9732/hints/sco.pl Time-HiRes-1.9732/hints/solaris.pl Time-HiRes-1.9732/hints/svr4.pl Time-HiRes-1.9732/fallback/const-c.inc Time-HiRes-1.9732/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9732.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 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.9732.tar.gz /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9732.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.9732\" -DXS_VERSION=\"1.9732\" "-I/Users/fly2000/ap2000-298465/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 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 Use of uninitialized value $dirs[0] in string eq at /Users/fly2000/cpanfly-5.20/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /Users/fly2000/cpanfly-5.20/var/megalib/Pod/Man.pm line 894. Manifying 1 pod document JHI/Time-HiRes-1.9732.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 9734, starting the watchdog process... # I am the watchdog process 9737, sleeping for 360 seconds... # The watchdog process 9737 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300746 # Select returned! 4 0.300876 # 0.899708 # Tick! 3 0.601037 # Select returned! 3 0.601161 # 0.799613 # Tick! 2 0.90132 # Select returned! 2 0.901446 # 0.699518 # Tick! 1 1.202646 # Select returned! 1 1.202769 # 0.599077 # Tick! 0 1.502922 # Select returned! 0 1.503041 # 0.498986333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1457975225.7274, t0 = 1457975225.72729, dt = 0.000108957290649414 # N = 2048, t1 = 1457975225.72759, t0 = 1457975225.72746, dt = 0.000132083892822266 # N = 4096, t1 = 1457975225.72792, t0 = 1457975225.72762, dt = 0.000292062759399414 # N = 8192, t1 = 1457975225.72855, t0 = 1457975225.72795, dt = 0.000602006912231445 # N = 16384, t1 = 1457975225.72965, t0 = 1457975225.7286, dt = 0.00104808807373047 # N = 32768, t1 = 1457975225.73293, t0 = 1457975225.72968, dt = 0.00325393676757812 # N = 65536, t1 = 1457975225.7372, t0 = 1457975225.73301, dt = 0.00418400764465332 # N = 131072, t1 = 1457975225.74725, t0 = 1457975225.73724, dt = 0.0100119113922119 # Alarm 1 - 1457975225.75764 # Alarm 2 - 1457975225.77487 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 9734, terminating the watchdog process 9737 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 9737 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 9822, starting the watchdog process... # I am the watchdog process 9823, sleeping for 360 seconds... # The watchdog process 9823 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 = 1457975231.44604, t0 = 1457975229.94592 # dt = 1.50011014938354, rt = 7.34329223632812e-05 ok 2 ok 3 ok 4 # clock = 0.045645 # clock = 0.045645 0.108804 # clock = 0.045645 0.108804 0.171855 # clock = 0.045645 0.108804 0.171855 0.235017 ok 5 # I am the main process 9822, terminating the watchdog process 9823 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 9823 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 9904, starting the watchdog process... # I am the watchdog process 9906, sleeping for 360 seconds... # The watchdog process 9906 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 9904, terminating the watchdog process 9906 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9906 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 9937, starting the watchdog process... # I am the watchdog process 9938, sleeping for 360 seconds... # The watchdog process 9938 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497421 0.4 # Tick! 2 0.542869 # Tick! 1 0.975401 # Tick! 0 1.402861 # Tick! 0 1.835941 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 9937, terminating the watchdog process 9938 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 9938 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 9975, starting the watchdog process... # I am the watchdog process 9978, sleeping for 360 seconds... # The watchdog process 9978 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 9975, terminating the watchdog process 9978 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 9978 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 9989, starting the watchdog process... # I am the watchdog process 9990, sleeping for 360 seconds... # The watchdog process 9990 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1457975236.73388 ok 3 # sleep...0.500047 ok 4 # I am the main process 9989, terminating the watchdog process 9990 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9990 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 10000, starting the watchdog process... # I am the watchdog process 10001, sleeping for 360 seconds... # The watchdog process 10001 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 = 1457975237 1457975237 1457975237 1457975238 1457975238 # atime = 1457975237 1457975237 1457975238 1457975238 1457975238 # 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 10000, terminating the watchdog process 10001 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 10001 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 10008, starting the watchdog process... # The watchdog process 10011 launched, continuing testing... # I am the watchdog process 10011, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 82.2635812759399, n = 100, s/n = 0.822635812759399 # I am the main process 10008, terminating the watchdog process 10011 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 10011 = 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 10014, starting the watchdog process... # The watchdog process 10015 launched, continuing testing... # I am the watchdog process 10015, sleeping for 360 seconds... ok 1 # tick = 1, one = 1457975238, two = 1457975238, three = 1457975238 ok 2 # tick = 3, one = 1457975238, two = 1457975238, three = 1457975238 ok 3 - negative time error # t0 = 1457975238.9771 # ualarm(100000) # t1 = 1457975239.07722 # dt = 0.100122213363647 # r = 1.00122213363647 ok 4 # t0 = 1457975239.07746 # ualarm(1100000) # t1 = 1457975240.17756 # dt = 1.10009789466858 # r = 1.00008899515325 ok 5 # t0 = 1457975240.17794 # ualarm(2200000) # t1 = 1457975242.37802 # dt = 2.20008206367493 # r = 1.00003730167042 ok 6 # t0 = 1457975242.3784 # ualarm(4300000) # t1 = 1457975246.67844 # dt = 4.30004096031189 # r = 1.00000952565393 ok 7 # t0 = 1457975246.67881 # got0 = 0 # t1 = 1457975246.97881 # t1 - t0 = 0.300000905990601 # got1 = 199982 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 10014, terminating the watchdog process 10015 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 10015 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 10054, starting the watchdog process... # The watchdog process 10057 launched, continuing testing... # I am the watchdog process 10057, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 10054, terminating the watchdog process 10057 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 10057 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.09 usr 0.03 sys + 13.75 cusr 0.17 csys = 14.04 CPU) Result: PASS JHI/Time-HiRes-1.9732.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/cpan/build/Time-HiRes-1.9732-lWLToE && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/Users/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI/Time-HiRes-1.9732.tar.gz Time-HiRes-1.9732.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/fly2000/cpanfly-5.20/var/cpan/build/Time-HiRes-1.9732-lWLToE/Time-HiRes-1.9732.ppd /Users/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI Finished 2016-03-14T10:07:31