PATH=/usr/bin:/bin:/Users/fly1600/cpanfly-5.16/var/megalib/bin Start 2016-03-15T03:02:10 ActivePerl-1600 CPAN-2.10 Reading '/Users/fly1600/cpanfly-5.16/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/fly1600/cpanfly-5.16/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/fly1600/ap1600/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.9732.tar.gz /Users/fly1600/ap1600/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/fly1600/ap1600/bin/perl-dynamic" "/Users/fly1600/cpanfly-5.16/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly1600/ap1600/lib/ExtUtils/typemap" -typemap "typemap" HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -arch i386 -arch x86_64 -nostdinc -B/Developer/SDKs/MacOSX10.6.sdk/usr/include/gcc -B/Developer/SDKs/MacOSX10.6.sdk/usr/lib/gcc -isystem/Developer/SDKs/MacOSX10.6.sdk/usr/include -F/Developer/SDKs/MacOSX10.6.sdk/System/Library/Frameworks -mmacosx-version-min=10.6 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9732\" -DXS_VERSION=\"1.9732\" "-I/Users/fly1600/ap1600/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 i386 -arch x86_64 -Wl,-syslibroot,/Developer/SDKs/MacOSX10.6.sdk -mmacosx-version-min=10.6 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly1600/ap1600/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/fly1600/cpanfly-5.16/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /Users/fly1600/cpanfly-5.16/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/fly1600/ap1600/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 2892, starting the watchdog process... # The watchdog process 2893 launched, continuing testing... # I am the watchdog process 2893, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300384 # Select returned! 4 0.300574 # 0.899808666666667 # Tick! 3 0.600973 # Select returned! 3 0.601159 # 0.799613666666667 # Tick! 2 0.901567 # Select returned! 2 0.901753 # 0.699415666666667 # Tick! 1 1.20216 # Select returned! 1 1.202336 # 0.599221333333333 # Tick! 0 1.502719 # Select returned! 0 1.5029 # 0.499033333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1458036145.59369, t0 = 1458036145.5936, dt = 9.48905944824219e-05 # N = 2048, t1 = 1458036145.59394, t0 = 1458036145.59376, dt = 0.000185012817382812 # N = 4096, t1 = 1458036145.59436, t0 = 1458036145.59399, dt = 0.000374078750610352 # N = 8192, t1 = 1458036145.59504, t0 = 1458036145.5944, dt = 0.000635147094726562 # N = 16384, t1 = 1458036145.59634, t0 = 1458036145.59507, dt = 0.00126910209655762 # N = 32768, t1 = 1458036145.59891, t0 = 1458036145.59638, dt = 0.00253391265869141 # N = 65536, t1 = 1458036145.60377, t0 = 1458036145.59895, dt = 0.00482296943664551 # N = 131072, t1 = 1458036145.61283, t0 = 1458036145.60381, dt = 0.00902414321899414 # N = 262144, t1 = 1458036145.63088, t0 = 1458036145.61287, dt = 0.0180099010467529 # Alarm 1 - 1458036145.641 # Alarm 2 - 1458036145.67734 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 2892, terminating the watchdog process 2893 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 2893 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 2894, starting the watchdog process... # The watchdog process 2895 launched, continuing testing... # I am the watchdog process 2895, 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 = 1458036151.41971, t0 = 1458036149.91943 # dt = 1.50027894973755, rt = 0.000185966491699219 ok 2 ok 3 ok 4 # clock = 0.043734 # clock = 0.043734 0.107811 # clock = 0.043734 0.107811 0.1691 # clock = 0.043734 0.107811 0.1691 0.231124 ok 5 # I am the main process 2894, terminating the watchdog process 2895 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 2895 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 2896, starting the watchdog process... # The watchdog process 2897 launched, continuing testing... # I am the watchdog process 2897, 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 2896, terminating the watchdog process 2897 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2897 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 2898, starting the watchdog process... # The watchdog process 2899 launched, continuing testing... # I am the watchdog process 2899, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497872 0.4 # Tick! 2 0.50844 # Tick! 1 0.91437 # Tick! 0 1.320833 # Tick! 0 1.727115 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 2898, terminating the watchdog process 2899 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2899 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 2900, starting the watchdog process... # The watchdog process 2903 launched, continuing testing... # I am the watchdog process 2903, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 2900, terminating the watchdog process 2903 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2903 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 2904, starting the watchdog process... # The watchdog process 2905 launched, continuing testing... # I am the watchdog process 2905, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1458036156.56515 ok 3 # sleep...0.50017 ok 4 # I am the main process 2904, terminating the watchdog process 2905 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2905 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 2906, starting the watchdog process... # The watchdog process 2907 launched, continuing testing... # I am the watchdog process 2907, 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 = 1458036157 1458036157 1458036157 1458036158 1458036158 # atime = 1458036157 1458036157 1458036158 1458036158 1458036158 # 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 2906, terminating the watchdog process 2907 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2907 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 2908, starting the watchdog process... # The watchdog process 2909 launched, continuing testing... # I am the watchdog process 2909, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 72.0077254772186, n = 100, s/n = 0.720077254772186 # I am the main process 2908, terminating the watchdog process 2909 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 2909 = 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 2911, starting the watchdog process... # The watchdog process 2912 launched, continuing testing... # I am the watchdog process 2912, sleeping for 360 seconds... ok 1 # tick = 1, one = 1458036158, two = 1458036158, three = 1458036158 ok 2 # tick = 3, one = 1458036158, two = 1458036158, three = 1458036158 ok 3 - negative time error # t0 = 1458036158.86077 # ualarm(100000) # t1 = 1458036158.9609 # dt = 0.100131988525391 # r = 1.00131988525391 ok 4 # t0 = 1458036158.96118 # ualarm(1100000) # t1 = 1458036160.06127 # dt = 1.10009384155273 # r = 1.00008531050249 ok 5 # t0 = 1458036160.0616 # ualarm(2200000) # t1 = 1458036162.26168 # dt = 2.20007586479187 # r = 1.0000344839963 ok 6 # t0 = 1458036162.26204 # ualarm(4300000) # t1 = 1458036166.56205 # dt = 4.30000805854797 # r = 1.00000187408092 ok 7 # t0 = 1458036166.56238 # got0 = 0 # t1 = 1458036166.86238 # t1 - t0 = 0.300000190734863 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 2911, terminating the watchdog process 2912 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 2912 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 2913, starting the watchdog process... # The watchdog process 2914 launched, continuing testing... # I am the watchdog process 2914, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 2913, terminating the watchdog process 2914 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 2914 = 1 # All done. ok All tests successful. Files=11, Tests=95, 28 wallclock secs ( 0.09 usr 0.03 sys + 14.61 cusr 0.14 csys = 14.87 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/fly1600/cpanfly-5.16/var/cpan/build/Time-HiRes-1.9732-YJlSmY && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/Users/fly1600/cpanfly-5.16/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/fly1600/cpanfly-5.16/var/cpan/build/Time-HiRes-1.9732-YJlSmY/Time-HiRes-1.9732.ppd /Users/fly1600/cpanfly-5.16/var/REPO/J/JH/JHI Finished 2016-03-15T03:02:51