PATH=/usr/bin:/bin Start 2011-06-08T18:17:48 ActivePerl-1003 CPAN-1.9402 Going to read '/home/fly1003/var/cpan/Metadata' Database was generated on Wed, 08 Jun 2011 18:32:20 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9723.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9723.tar.gz ok Will not use Archive::Tar, need 1.00 Time-HiRes-1.9723/ Time-HiRes-1.9723/META.yml Time-HiRes-1.9723/MANIFEST Time-HiRes-1.9723/HiRes.pm Time-HiRes-1.9723/Changes Time-HiRes-1.9723/ppport.h Time-HiRes-1.9723/README Time-HiRes-1.9723/hints/ Time-HiRes-1.9723/hints/aix.pl Time-HiRes-1.9723/hints/dynixptx.pl Time-HiRes-1.9723/hints/solaris.pl Time-HiRes-1.9723/hints/linux.pl Time-HiRes-1.9723/hints/irix.pl Time-HiRes-1.9723/hints/dec_osf.pl Time-HiRes-1.9723/hints/svr4.pl Time-HiRes-1.9723/hints/sco.pl Time-HiRes-1.9723/TODO Time-HiRes-1.9723/HiRes.xs Time-HiRes-1.9723/fallback/ Time-HiRes-1.9723/fallback/const-c.inc Time-HiRes-1.9723/fallback/const-xs.inc Time-HiRes-1.9723/typemap Time-HiRes-1.9723/Makefile.PL Time-HiRes-1.9723/t/ Time-HiRes-1.9723/t/Watchdog.pm Time-HiRes-1.9723/t/sleep.t Time-HiRes-1.9723/t/nanosleep.t Time-HiRes-1.9723/t/itimer.t Time-HiRes-1.9723/t/usleep.t Time-HiRes-1.9723/t/ualarm.t Time-HiRes-1.9723/t/clock.t Time-HiRes-1.9723/t/gettimeofday.t Time-HiRes-1.9723/t/time.t Time-HiRes-1.9723/t/tv_interval.t Time-HiRes-1.9723/t/stat.t Time-HiRes-1.9723/t/alarm.t CPAN.pm: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9723.tar.gz >>> /home/fly1003/ap1003/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/fly1003/ap1003/bin/perl-static /home/fly1003/var/megalib/ExtUtils/xsubpp -typemap /home/fly1003/ap1003/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DUSE_SITECUSTOMIZE -DPRIVLIB_LAST_IN_INC -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9723\" -DXS_VERSION=\"1.9723\" -fPIC "-I/home/fly1003/ap1003/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.9723.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly1003/ap1003/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 25640, starting the watchdog process... # I am the watchdog process 25641, sleeping for 360 seconds... # The watchdog process 25641 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.337435 # Select returned! 4 0.337602 # 0.887466 # Tick! 3 0.638335 # Select returned! 3 0.638524 # 0.787158666666667 # Tick! 2 0.939542 # Select returned! 2 0.939824 # 0.686725333333333 # Tick! 1 1.241156 # Select returned! 1 1.241585 # 0.586138333333333 # Tick! 0 1.543128 # Select returned! 0 1.543587 # 0.485471 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307582274.59118, t0 = 1307582274.59102, dt = 0.000164985656738281 # N = 2048, t1 = 1307582274.5914, t0 = 1307582274.59126, dt = 0.000134944915771484 # N = 4096, t1 = 1307582274.59166, t0 = 1307582274.59145, dt = 0.000208854675292969 # N = 8192, t1 = 1307582274.59211, t0 = 1307582274.5917, dt = 0.000412940979003906 # N = 16384, t1 = 1307582274.59234, t0 = 1307582274.59215, dt = 0.000195980072021484 # N = 32768, t1 = 1307582274.59318, t0 = 1307582274.59243, dt = 0.000755071640014648 # N = 65536, t1 = 1307582274.59518, t0 = 1307582274.5938, dt = 0.00138688087463379 # N = 131072, t1 = 1307582274.60383, t0 = 1307582274.59818, dt = 0.00565099716186523 # N = 262144, t1 = 1307582274.61782, t0 = 1307582274.60466, dt = 0.0131618976593018 # Alarm 1 - 1307582274.63117 # Alarm 2 - 1307582274.66562 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 25640, terminating the watchdog process 25641 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 25641 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 25643, starting the watchdog process... # I am the watchdog process 25644, sleeping for 360 seconds... # The watchdog process 25644 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 = 1307582280.36473, t0 = 1307582278.86051 # dt = 1.5042200088501, rt = 0.00281333923339844 ok 2 ok 3 ok 4 # clock = 0.01 # clock = 0.01 0.08 # clock = 0.01 0.08 0.13 # clock = 0.01 0.08 0.13 0.19 ok 5 # I am the main process 25643, terminating the watchdog process 25644 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 25644 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 25645, starting the watchdog process... # I am the watchdog process 25646, sleeping for 360 seconds... # The watchdog process 25646 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 25645, terminating the watchdog process 25646 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25646 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 25647, starting the watchdog process... # I am the watchdog process 25648, sleeping for 360 seconds... # The watchdog process 25648 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.517174 # Tick! 1 0.940969 # Tick! 0 1.396988 # Tick! 0 1.835119 # getitimer: 0 0 ok 2 # I am the main process 25647, terminating the watchdog process 25648 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25648 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 25649, starting the watchdog process... # I am the watchdog process 25650, sleeping for 360 seconds... # The watchdog process 25650 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 25649, terminating the watchdog process 25650 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25650 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 25651, starting the watchdog process... # I am the watchdog process 25652, sleeping for 360 seconds... # The watchdog process 25652 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307582285.54992 ok 3 # sleep...0.500381 ok 4 # I am the main process 25651, terminating the watchdog process 25652 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25652 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 25653, starting the watchdog process... # I am the watchdog process 25654, sleeping for 360 seconds... # The watchdog process 25654 launched, continuing testing... # mtime = 1307582286 1307582286 1307582286 1307582287 1307582287 # atime = 1307582286 1307582286 1307582286 1307582287 1307582287 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 25653, terminating the watchdog process 25654 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25654 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 25655, starting the watchdog process... # I am the watchdog process 25656, sleeping for 360 seconds... # The watchdog process 25656 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 54.5768501758575, n = 100, s/n = 0.545768501758575 # I am the main process 25655, terminating the watchdog process 25656 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 25656 = 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 25658, starting the watchdog process... # I am the watchdog process 25659, sleeping for 360 seconds... # The watchdog process 25659 launched, continuing testing... ok 1 # tick = 1, one = 1307582287, two = 1307582287, three = 1307582287 ok 2 # tick = 3, one = 1307582287, two = 1307582287, three = 1307582287 ok 3 - negative time error # t0 = 1307582287.66131 # ualarm(100000) # t1 = 1307582287.76502 # dt = 0.103706121444702 # r = 1.03706121444702 ok 4 # t0 = 1307582287.76701 # ualarm(1100000) # t1 = 1307582288.86898 # dt = 1.10197186470032 # r = 1.00179260427302 ok 5 # t0 = 1307582288.87245 # ualarm(2200000) # t1 = 1307582291.07341 # dt = 2.20095419883728 # r = 1.00043372674422 ok 6 # t0 = 1307582291.07551 # ualarm(4300000) # t1 = 1307582295.3767 # dt = 4.30118918418884 # r = 1.00027655446252 ok 7 # t0 = 1307582295.37884 # got0 = 0 # t1 = 1307582295.67885 # t1 - t0 = 0.3000168800354 # got1 = 200010 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 25658, terminating the watchdog process 25659 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 25659 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 25661, starting the watchdog process... # I am the watchdog process 25662, sleeping for 360 seconds... # The watchdog process 25662 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 25661, terminating the watchdog process 25662 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 25662 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.06 usr 0.05 sys + 14.05 cusr 0.45 csys = 14.61 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9723.tar.gz make test TEST_VERBOSE=1 -- OK Andrew Main (Zefram) <zefram@fysh.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1003/var/cpan/build/Time-HiRes-1.9723-CtQgTy && tar cvf - Time-HiRes-1.9723.ppd blib) | gzip -c >/home/fly1003/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9723.tar.gz Time-HiRes-1.9723.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/fly1003/var/cpan/build/Time-HiRes-1.9723-CtQgTy/Time-HiRes-1.9723.ppd /home/fly1003/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-08T18:18:20