PATH=/usr/bin:/bin Start 2011-06-10T21:06:35 ActivePerl-1200 CPAN-1.9402 Going to read '/home/fly1200/var/cpan/Metadata' Database was generated on Fri, 10 Jun 2011 18:29:59 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9724.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9724.tar.gz ok Time-HiRes-1.9724/ Time-HiRes-1.9724/META.yml Time-HiRes-1.9724/MANIFEST Time-HiRes-1.9724/HiRes.pm Time-HiRes-1.9724/Changes Time-HiRes-1.9724/ppport.h Time-HiRes-1.9724/README Time-HiRes-1.9724/hints/ Time-HiRes-1.9724/hints/aix.pl Time-HiRes-1.9724/hints/dynixptx.pl Time-HiRes-1.9724/hints/solaris.pl Time-HiRes-1.9724/hints/linux.pl Time-HiRes-1.9724/hints/irix.pl Time-HiRes-1.9724/hints/dec_osf.pl Time-HiRes-1.9724/hints/svr4.pl Time-HiRes-1.9724/hints/sco.pl Time-HiRes-1.9724/TODO Time-HiRes-1.9724/HiRes.xs Time-HiRes-1.9724/fallback/ Time-HiRes-1.9724/fallback/const-c.inc Time-HiRes-1.9724/fallback/const-xs.inc Time-HiRes-1.9724/typemap Time-HiRes-1.9724/Makefile.PL Time-HiRes-1.9724/t/ Time-HiRes-1.9724/t/Watchdog.pm Time-HiRes-1.9724/t/sleep.t Time-HiRes-1.9724/t/nanosleep.t Time-HiRes-1.9724/t/itimer.t Time-HiRes-1.9724/t/usleep.t Time-HiRes-1.9724/t/ualarm.t Time-HiRes-1.9724/t/clock.t Time-HiRes-1.9724/t/gettimeofday.t Time-HiRes-1.9724/t/time.t Time-HiRes-1.9724/t/tv_interval.t Time-HiRes-1.9724/t/stat.t Time-HiRes-1.9724/t/alarm.t CPAN.pm: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9724.tar.gz >>> /home/fly1200/ap1200-292396/bin/perl-static Makefile.PL Configuring Time::HiRes... Using hints hints/linux.pl... Extra libraries: -lrt... 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. Looking for clock_getres()... found. Looking for clock_nanosleep()... found. Looking for clock()... found. Looking for stat() subsecond timestamps... Trying struct stat st_atimespec.tv_nsec...NOT found. Trying struct stat st_atimensec...NOT found. Trying struct stat st_atime_n...NOT found. Trying struct stat st_atim.tv_nsec...found. Trying struct stat st_uatime...Processing hints file hints/linux.pl 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 Writing Makefile for Time::HiRes Now you may issue 'make'. Do not forget also 'make test'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm /home/fly1200/ap1200-292396/bin/perl-static /home/fly1200/var/megalib/ExtUtils/xsubpp -typemap /home/fly1200/ap1200-292396/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DUSE_SITECUSTOMIZE -fno-merge-constants -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9724\" -DXS_VERSION=\"1.9724\" -fPIC "-I/home/fly1200/ap1200-292396/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT=4 -DATLEASTFIVEOHOHFIVE HiRes.c Running Mkbootstrap for Time::HiRes () chmod 644 HiRes.bs rm -f blib/arch/auto/Time/HiRes/HiRes.so LD_RUN_PATH="/lib64" gcc -shared -O2 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so cp HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs chmod 644 blib/arch/auto/Time/HiRes/HiRes.bs Manifying blib/man3/Time::HiRes.3 ZEFRAM/Time-HiRes-1.9724.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly1200/ap1200-292396/bin/perl-static "-MExtUtils::Command::MM" "-e" "test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/alarm.t ......... 1..10 # I am the main process 10385, starting the watchdog process... # I am the watchdog process 10386, sleeping for 360 seconds... # The watchdog process 10386 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.31997 # Select returned! 4 0.320257 # 0.893247666666667 # Tick! 3 0.621822 # Select returned! 3 0.622181 # 0.792606333333333 # Tick! 2 0.922726 # Select returned! 2 0.922826 # 0.692391333333333 # Tick! 1 1.224483 # Select returned! 1 1.224957 # 0.591681 # Tick! 0 1.526419 # Select returned! 0 1.526743 # 0.491085666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307765202.24583, t0 = 1307765202.24576, dt = 6.60419464111328e-05 # N = 2048, t1 = 1307765202.24604, t0 = 1307765202.24589, dt = 0.000141143798828125 # N = 4096, t1 = 1307765202.24626, t0 = 1307765202.24609, dt = 0.000163078308105469 # N = 8192, t1 = 1307765202.24682, t0 = 1307765202.24632, dt = 0.000504016876220703 # N = 16384, t1 = 1307765202.24803, t0 = 1307765202.24687, dt = 0.0011591911315918 # N = 32768, t1 = 1307765202.25016, t0 = 1307765202.24818, dt = 0.00197410583496094 # N = 65536, t1 = 1307765202.25602, t0 = 1307765202.25187, dt = 0.00414705276489258 # N = 131072, t1 = 1307765202.2647, t0 = 1307765202.25611, dt = 0.00859904289245605 # N = 262144, t1 = 1307765202.28157, t0 = 1307765202.26484, dt = 0.0167269706726074 # Alarm 1 - 1307765202.29241 # Alarm 2 - 1307765202.32626 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 10385, terminating the watchdog process 10386 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 10386 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 10387, starting the watchdog process... # I am the watchdog process 10388, sleeping for 360 seconds... # The watchdog process 10388 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 = 1307765208.0638, t0 = 1307765206.55911 # dt = 1.5046820640564, rt = 0.00312137603759766 ok 2 ok 3 ok 4 # clock = 0.02 # clock = 0.02 0.09 # clock = 0.02 0.09 0.15 # clock = 0.02 0.09 0.15 0.21 ok 5 # I am the main process 10387, terminating the watchdog process 10388 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 10388 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 10389, starting the watchdog process... # I am the watchdog process 10390, sleeping for 360 seconds... # The watchdog process 10390 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 10389, terminating the watchdog process 10390 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 10390 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 10391, starting the watchdog process... # I am the watchdog process 10392, sleeping for 360 seconds... # The watchdog process 10392 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.50613 # Tick! 1 0.907312 # Tick! 0 1.313438 # Tick! 0 1.714503 # getitimer: 0 0 ok 2 # I am the main process 10391, terminating the watchdog process 10392 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 10392 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 10394, starting the watchdog process... # I am the watchdog process 10395, sleeping for 360 seconds... # The watchdog process 10395 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 10394, terminating the watchdog process 10395 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 10395 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 10396, starting the watchdog process... # I am the watchdog process 10397, sleeping for 360 seconds... # The watchdog process 10397 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307765213.13933 ok 3 # sleep...0.501635 ok 4 # I am the main process 10396, terminating the watchdog process 10397 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 10397 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 10398, starting the watchdog process... # I am the watchdog process 10399, sleeping for 360 seconds... # The watchdog process 10399 launched, continuing testing... # mtime = 1307765213 1307765214 1307765214 1307765214 1307765215 # atime = 1307765214 1307765214 1307765214 1307765214 1307765215 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 10398, terminating the watchdog process 10399 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 10399 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 10400, starting the watchdog process... # I am the watchdog process 10401, sleeping for 360 seconds... # The watchdog process 10401 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 33.8657841682434, n = 100, s/n = 0.338657841682434 # I am the main process 10400, terminating the watchdog process 10401 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 10401 = 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 10403, starting the watchdog process... # I am the watchdog process 10404, sleeping for 360 seconds... # The watchdog process 10404 launched, continuing testing... ok 1 # tick = 1, one = 1307765215, two = 1307765215, three = 1307765215 ok 2 # tick = 3, one = 1307765215, two = 1307765215, three = 1307765215 ok 3 - negative time error # t0 = 1307765215.46018 # ualarm(100000) # t1 = 1307765215.56093 # dt = 0.100752115249634 # r = 1.00752115249634 ok 4 # t0 = 1307765215.56251 # ualarm(1100000) # t1 = 1307765216.66426 # dt = 1.1017599105835 # r = 1.00159991871227 ok 5 # t0 = 1307765216.66559 # ualarm(2200000) # t1 = 1307765218.86686 # dt = 2.20127201080322 # r = 1.00057818672874 ok 6 # t0 = 1307765218.86772 # ualarm(4300000) # t1 = 1307765223.16857 # dt = 4.30084204673767 # r = 1.00019582482271 ok 7 # t0 = 1307765223.17048 # got0 = 0 # t1 = 1307765223.47048 # t1 - t0 = 0.300004005432129 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 10403, terminating the watchdog process 10404 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 10404 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 10405, starting the watchdog process... # The watchdog process 10406 launched, continuing testing... # I am the watchdog process 10406, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 10405, terminating the watchdog process 10406 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 10406 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.06 usr 0.02 sys + 14.37 cusr 0.17 csys = 14.62 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9724.tar.gz make test TEST_VERBOSE=1 -- OK Andrew Main (Zefram) <zefram@fysh.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1200/var/cpan/build/Time-HiRes-1.9724-7Rw1Vx && tar cvf - Time-HiRes-1.9724.ppd blib) | gzip -c >/home/fly1200/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9724.tar.gz Time-HiRes-1.9724.ppd blib/ blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.so blib/arch/auto/Time/HiRes/HiRes.bs blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1200/var/cpan/build/Time-HiRes-1.9724-7Rw1Vx/Time-HiRes-1.9724.ppd /home/fly1200/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-10T21:07:07