PATH=/usr/bin:/bin:/Users/fly1400/cpanfly-5.14/var/megalib/bin Start 2016-03-15T01:07:19 ActivePerl-1400 CPAN-2.10 Reading '/Users/fly1400/cpanfly-5.14/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/fly1400/cpanfly-5.14/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/fly1400/ap1400/bin/perl 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/fly1400/ap1400/bin/perl 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/fly1400/ap1400/bin/perl" "/Users/fly1400/cpanfly-5.14/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly1400/ap1400/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.5.sdk/usr/include/gcc -B/Developer/SDKs/MacOSX10.5.sdk/usr/lib/gcc -isystem/Developer/SDKs/MacOSX10.5.sdk/usr/include -F/Developer/SDKs/MacOSX10.5.sdk/System/Library/Frameworks -mmacosx-version-min=10.5 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9732\" -DXS_VERSION=\"1.9732\" "-I/Users/fly1400/ap1400/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.5.sdk -mmacosx-version-min=10.5 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly1400/ap1400/bin/perl" -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/fly1400/cpanfly-5.14/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /Users/fly1400/cpanfly-5.14/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/fly1400/ap1400/bin/perl" "-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 98159, starting the watchdog process... # The watchdog process 98163 launched, continuing testing... # I am the watchdog process 98163, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.522685 # Select returned! 4 0.52289 # 0.825703333333333 # Tick! 3 0.823238 # Select returned! 3 0.823369 # 0.725543666666667 # Tick! 2 1.123624 # Select returned! 2 1.123782 # 0.625406 # Tick! 1 1.424121 # Select returned! 1 1.424309 # 0.525230333333333 # Tick! 0 1.72464 # Select returned! 0 1.72479 # 0.42507 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1458029275.41346, t0 = 1458029275.41335, dt = 0.000104904174804688 # N = 2048, t1 = 1458029275.41374, t0 = 1458029275.41352, dt = 0.000213146209716797 # N = 4096, t1 = 1458029275.41413, t0 = 1458029275.41378, dt = 0.000353097915649414 # N = 8192, t1 = 1458029275.41487, t0 = 1458029275.41418, dt = 0.000694036483764648 # N = 16384, t1 = 1458029275.41613, t0 = 1458029275.4149, dt = 0.00122380256652832 # N = 32768, t1 = 1458029275.41859, t0 = 1458029275.41617, dt = 0.00241994857788086 # N = 65536, t1 = 1458029275.42333, t0 = 1458029275.41862, dt = 0.00471186637878418 # N = 131072, t1 = 1458029275.43198, t0 = 1458029275.42337, dt = 0.00861096382141113 # N = 262144, t1 = 1458029275.44923, t0 = 1458029275.43203, dt = 0.0171980857849121 # Alarm 1 - 1458029275.45933 # Alarm 2 - 1458029275.49393 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 98159, terminating the watchdog process 98163 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 98163 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 98164, starting the watchdog process... # The watchdog process 98167 launched, continuing testing... # I am the watchdog process 98167, 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 = 1458029281.4732, t0 = 1458029279.97295 # dt = 1.50025129318237, rt = 0.000167528788248772 ok 2 ok 3 ok 4 # clock = 0.041675 # clock = 0.041675 0.108136 # clock = 0.041675 0.108136 0.173086 # clock = 0.041675 0.108136 0.173086 0.237986 ok 5 # I am the main process 98164, terminating the watchdog process 98167 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 98167 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 98168, starting the watchdog process... # The watchdog process 98169 launched, continuing testing... # I am the watchdog process 98169, 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 98168, terminating the watchdog process 98169 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98169 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 98170, starting the watchdog process... # The watchdog process 98171 launched, continuing testing... # I am the watchdog process 98171, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.49841 0.4 # Tick! 2 0.509229 # Tick! 1 0.91633 # Tick! 0 1.336901 # Tick! 0 1.744407 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 98170, terminating the watchdog process 98171 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 98171 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 98172, starting the watchdog process... # The watchdog process 98173 launched, continuing testing... # I am the watchdog process 98173, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 98172, terminating the watchdog process 98173 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 98173 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 98174, starting the watchdog process... # The watchdog process 98175 launched, continuing testing... # I am the watchdog process 98175, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1458029286.68404 ok 3 # sleep...0.500151 ok 4 # I am the main process 98174, terminating the watchdog process 98175 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 98175 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 98176, starting the watchdog process... # The watchdog process 98177 launched, continuing testing... # I am the watchdog process 98177, 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 = 1458029287 1458029287 1458029288 1458029288 1458029288 # atime = 1458029287 1458029288 1458029288 1458029288 1458029289 # 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 98176, terminating the watchdog process 98177 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 98177 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 98178, starting the watchdog process... # The watchdog process 98179 launched, continuing testing... # I am the watchdog process 98179, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 26.359142780304, n = 100, s/n = 0.26359142780304 # I am the main process 98178, terminating the watchdog process 98179 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 98179 = 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 98181, starting the watchdog process... # The watchdog process 98182 launched, continuing testing... # I am the watchdog process 98182, sleeping for 360 seconds... ok 1 # tick = 1, one = 1458029289, two = 1458029289, three = 1458029289 ok 2 # tick = 3, one = 1458029289, two = 1458029289, three = 1458029289 ok 3 - negative time error # t0 = 1458029289.4111 # ualarm(100000) # t1 = 1458029289.51123 # dt = 0.100127935409546 # r = 1.00127935409546 ok 4 # t0 = 1458029289.51153 # ualarm(1100000) # t1 = 1458029290.61163 # dt = 1.100093126297 # r = 1.00008466027 ok 5 # t0 = 1458029290.61197 # ualarm(2200000) # t1 = 1458029292.81203 # dt = 2.20006084442139 # r = 1.00002765655518 ok 6 # t0 = 1458029292.81241 # ualarm(4300000) # t1 = 1458029297.11245 # dt = 4.30004096031189 # r = 1.00000952565393 ok 7 # t0 = 1458029297.11283 # got0 = 0 # t1 = 1458029297.41283 # t1 - t0 = 0.300000905990601 # got1 = 199999 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 98181, terminating the watchdog process 98182 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 98182 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 98183, starting the watchdog process... # The watchdog process 98184 launched, continuing testing... # I am the watchdog process 98184, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 98183, terminating the watchdog process 98184 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 98184 = 1 # All done. ok All tests successful. Files=11, Tests=95, 29 wallclock secs ( 0.09 usr 0.04 sys + 14.61 cusr 0.15 csys = 14.89 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/fly1400/cpanfly-5.14/var/cpan/build/Time-HiRes-1.9732-nD1OLg && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/Users/fly1400/cpanfly-5.14/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/fly1400/cpanfly-5.14/var/cpan/build/Time-HiRes-1.9732-nD1OLg/Time-HiRes-1.9732.ppd /Users/fly1400/cpanfly-5.14/var/REPO/J/JH/JHI Finished 2016-03-15T01:08:22