PATH=/usr/bin:/bin:/Users/fly1600/cpanfly-5.16/var/megalib/bin Start 2016-04-25T03:23:03 ActivePerl-1600 CPAN-2.10 Reading '/Users/fly1600/cpanfly-5.16/var/cpan/Metadata' Database was generated on Sun, 24 Apr 2016 04:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9733.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.9733.tar.gz ok Time-HiRes-1.9733/ Time-HiRes-1.9733/Changes Time-HiRes-1.9733/fallback/ Time-HiRes-1.9733/hints/ Time-HiRes-1.9733/HiRes.pm Time-HiRes-1.9733/HiRes.xs Time-HiRes-1.9733/Makefile.PL Time-HiRes-1.9733/MANIFEST Time-HiRes-1.9733/META.json Time-HiRes-1.9733/META.yml Time-HiRes-1.9733/ppport.h Time-HiRes-1.9733/README Time-HiRes-1.9733/t/ Time-HiRes-1.9733/TODO Time-HiRes-1.9733/typemap Time-HiRes-1.9733/t/alarm.t Time-HiRes-1.9733/t/clock.t Time-HiRes-1.9733/t/gettimeofday.t Time-HiRes-1.9733/t/itimer.t Time-HiRes-1.9733/t/nanosleep.t Time-HiRes-1.9733/t/sleep.t Time-HiRes-1.9733/t/stat.t Time-HiRes-1.9733/t/time.t Time-HiRes-1.9733/t/tv_interval.t Time-HiRes-1.9733/t/ualarm.t Time-HiRes-1.9733/t/usleep.t Time-HiRes-1.9733/t/Watchdog.pm Time-HiRes-1.9733/hints/aix.pl Time-HiRes-1.9733/hints/dec_osf.pl Time-HiRes-1.9733/hints/dynixptx.pl Time-HiRes-1.9733/hints/irix.pl Time-HiRes-1.9733/hints/linux.pl Time-HiRes-1.9733/hints/sco.pl Time-HiRes-1.9733/hints/solaris.pl Time-HiRes-1.9733/hints/svr4.pl Time-HiRes-1.9733/fallback/const-c.inc Time-HiRes-1.9733/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9733.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.9733.tar.gz /Users/fly1600/ap1600/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9733.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.9733\" -DXS_VERSION=\"1.9733\" "-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 Manifying 1 pod document JHI/Time-HiRes-1.9733.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 42410, starting the watchdog process... # I am the watchdog process 42411, sleeping for 360 seconds... # The watchdog process 42411 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300207 # Select returned! 4 0.300335 # 0.899888333333333 # Tick! 3 0.600455 # Select returned! 3 0.600543 # 0.799819 # Tick! 2 0.90064 # Select returned! 2 0.900723 # 0.699759 # Tick! 1 1.20083 # Select returned! 1 1.200917 # 0.599694333333333 # Tick! 0 1.50102 # Select returned! 0 1.501092 # 0.499636 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1461579799.60712, t0 = 1461579799.60704, dt = 7.00950622558594e-05 # N = 2048, t1 = 1461579799.60731, t0 = 1461579799.60717, dt = 0.00014495849609375 # N = 4096, t1 = 1461579799.60762, t0 = 1461579799.60735, dt = 0.000269174575805664 # N = 8192, t1 = 1461579799.60819, t0 = 1461579799.60765, dt = 0.000536203384399414 # N = 16384, t1 = 1461579799.60929, t0 = 1461579799.60822, dt = 0.00107002258300781 # N = 32768, t1 = 1461579799.61147, t0 = 1461579799.60933, dt = 0.00213885307312012 # N = 65536, t1 = 1461579799.61578, t0 = 1461579799.6115, dt = 0.00428009033203125 # N = 131072, t1 = 1461579799.62437, t0 = 1461579799.61582, dt = 0.00855278968811035 # N = 262144, t1 = 1461579799.64163, t0 = 1461579799.6244, dt = 0.0172219276428223 # Alarm 1 - 1461579799.65262 # Alarm 2 - 1461579799.69076 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 42410, terminating the watchdog process 42411 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 42411 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 42412, starting the watchdog process... # The watchdog process 42413 launched, continuing testing... # I am the watchdog process 42413, 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 = 1461579805.43845, t0 = 1461579803.9384 # dt = 1.50004529953003, rt = 3.01996866862719e-05 ok 2 ok 3 ok 4 # clock = 0.042941 # clock = 0.042941 0.109223 # clock = 0.042941 0.109223 0.170657 # clock = 0.042941 0.109223 0.170657 0.232127 ok 5 # I am the main process 42412, terminating the watchdog process 42413 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 42413 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 42414, starting the watchdog process... # The watchdog process 42415 launched, continuing testing... # I am the watchdog process 42415, 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 42414, terminating the watchdog process 42415 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 42415 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 42416, starting the watchdog process... # The watchdog process 42417 launched, continuing testing... # I am the watchdog process 42417, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497867 0.4 # Tick! 2 0.522076 # Tick! 1 0.938198 # Tick! 0 1.354142 # Tick! 0 1.770997 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 42416, terminating the watchdog process 42417 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 42417 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 42418, starting the watchdog process... # The watchdog process 42419 launched, continuing testing... # I am the watchdog process 42419, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 42418, terminating the watchdog process 42419 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 42419 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 42420, starting the watchdog process... # The watchdog process 42421 launched, continuing testing... # I am the watchdog process 42421, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1461579810.66974 ok 3 # sleep...0.50001 ok 4 # I am the main process 42420, terminating the watchdog process 42421 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 42421 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 42422, starting the watchdog process... # The watchdog process 42423 launched, continuing testing... # I am the watchdog process 42423, 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 = 1461579811 1461579811 1461579811 1461579812 1461579812 # atime = 1461579811 1461579811 1461579812 1461579812 1461579812 # 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 42422, terminating the watchdog process 42423 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 42423 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 42424, starting the watchdog process... # The watchdog process 42425 launched, continuing testing... # I am the watchdog process 42425, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 72.8049590587616, n = 100, s/n = 0.728049590587616 # I am the main process 42424, terminating the watchdog process 42425 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 42425 = 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 42427, starting the watchdog process... # I am the watchdog process 42428, sleeping for 360 seconds... # The watchdog process 42428 launched, continuing testing... ok 1 # tick = 1, one = 1461579812, two = 1461579812, three = 1461579812 ok 2 # tick = 3, one = 1461579812, two = 1461579812, three = 1461579812 ok 3 - negative time error # t0 = 1461579812.87444 # ualarm(100000) # t1 = 1461579812.97463 # dt = 0.100193023681641 # r = 1.00193023681641 ok 4 # t0 = 1461579812.97495 # ualarm(1100000) # t1 = 1461579814.07511 # dt = 1.10016298294067 # r = 1.0001481663097 ok 5 # t0 = 1461579814.07605 # ualarm(2200000) # t1 = 1461579816.27648 # dt = 2.20042991638184 # r = 1.0001954165372 ok 6 # t0 = 1461579816.27684 # ualarm(4300000) # t1 = 1461579820.57695 # dt = 4.30011177062988 # r = 1.00002599316974 ok 7 # t0 = 1461579820.57734 # got0 = 0 # t1 = 1461579820.87734 # t1 - t0 = 0.300000905990601 # got1 = 199985 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 42427, terminating the watchdog process 42428 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 42428 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 42432, starting the watchdog process... # The watchdog process 42435 launched, continuing testing... # I am the watchdog process 42435, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 42432, terminating the watchdog process 42435 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 42435 = 1 # All done. ok All tests successful. Files=11, Tests=95, 26 wallclock secs ( 0.08 usr 0.03 sys + 14.34 cusr 0.17 csys = 14.62 CPU) Result: PASS JHI/Time-HiRes-1.9733.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.9733-FIxd_s && tar cvf - Time-HiRes-1.9733.ppd blib) | gzip -c >/Users/fly1600/cpanfly-5.16/var/REPO/J/JH/JHI/Time-HiRes-1.9733.tar.gz Time-HiRes-1.9733.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.9733-FIxd_s/Time-HiRes-1.9733.ppd /Users/fly1600/cpanfly-5.16/var/REPO/J/JH/JHI Finished 2016-04-25T03:23:45