PATH=/usr/bin:/bin Start 2011-06-11T00:06:40 ActivePerl-1400 CPAN-1.9402 Going to read '/home/fly1400/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/fly1400/ap1400/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 Writing MYMETA.yml Now you may issue 'make'. Do not forget also 'make test'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm /home/fly1400/ap1400/bin/perl-static /home/fly1400/var/megalib/ExtUtils/xsubpp -typemap /home/fly1400/ap1400/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -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/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=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/fly1400/ap1400/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 19365, starting the watchdog process... # I am the watchdog process 19366, sleeping for 360 seconds... # The watchdog process 19366 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.322211 # Select returned! 4 0.32271 # 0.89243 # Tick! 3 0.624718 # Select returned! 3 0.624953 # 0.791682333333333 # Tick! 2 0.926695 # Select returned! 2 0.926943 # 0.691019 # Tick! 1 1.228597 # Select returned! 1 1.228815 # 0.590395 # Tick! 0 1.530592 # Select returned! 0 1.530853 # 0.489715666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307776007.03613, t0 = 1307776007.03592, dt = 0.000210046768188477 # N = 2048, t1 = 1307776007.03652, t0 = 1307776007.03627, dt = 0.000247955322265625 # N = 4096, t1 = 1307776007.03716, t0 = 1307776007.03661, dt = 0.000545978546142578 # N = 8192, t1 = 1307776007.03792, t0 = 1307776007.03731, dt = 0.000607013702392578 # N = 16384, t1 = 1307776007.03914, t0 = 1307776007.0381, dt = 0.00104308128356934 # N = 32768, t1 = 1307776007.04461, t0 = 1307776007.03996, dt = 0.00465512275695801 # N = 65536, t1 = 1307776007.05187, t0 = 1307776007.04512, dt = 0.00675106048583984 # N = 131072, t1 = 1307776007.06527, t0 = 1307776007.05206, dt = 0.0132091045379639 # Alarm 1 - 1307776007.07603 # Alarm 2 - 1307776007.09006 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 19365, terminating the watchdog process 19366 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 19366 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 19367, starting the watchdog process... # I am the watchdog process 19368, sleeping for 360 seconds... # The watchdog process 19368 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 = 1307776012.69785, t0 = 1307776011.19606 # dt = 1.50179100036621, rt = 0.00119400024414062 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 19367, terminating the watchdog process 19368 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 19368 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 19369, starting the watchdog process... # I am the watchdog process 19370, sleeping for 360 seconds... # The watchdog process 19370 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19369, terminating the watchdog process 19370 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19370 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 19371, starting the watchdog process... # I am the watchdog process 19372, sleeping for 360 seconds... # The watchdog process 19372 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.504566 # Tick! 1 0.908289 # Tick! 0 1.319022 # Tick! 0 1.727962 # getitimer: 0 0 ok 2 # I am the main process 19371, terminating the watchdog process 19372 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 19372 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 19373, starting the watchdog process... # I am the watchdog process 19374, sleeping for 360 seconds... # The watchdog process 19374 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 19373, terminating the watchdog process 19374 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19374 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 19375, starting the watchdog process... # I am the watchdog process 19376, sleeping for 360 seconds... # The watchdog process 19376 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307776017.7756 ok 3 # sleep...0.502836 ok 4 # I am the main process 19375, terminating the watchdog process 19376 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19376 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 19388, starting the watchdog process... # I am the watchdog process 19390, sleeping for 360 seconds... # The watchdog process 19390 launched, continuing testing... # mtime = 1307776018 1307776018 1307776019 1307776019 1307776019 # atime = 1307776018 1307776018 1307776019 1307776019 1307776019 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 19388, terminating the watchdog process 19390 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19390 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 19410, starting the watchdog process... # I am the watchdog process 19411, sleeping for 360 seconds... # The watchdog process 19411 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 90.592432975769, n = 100, s/n = 0.90592432975769 # I am the main process 19410, terminating the watchdog process 19411 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19411 = 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 19413, starting the watchdog process... # I am the watchdog process 19414, sleeping for 360 seconds... # The watchdog process 19414 launched, continuing testing... ok 1 # tick = 1, one = 1307776019, two = 1307776019, three = 1307776019 ok 2 # tick = 3, one = 1307776019, two = 1307776019, three = 1307776019 ok 3 - negative time error # t0 = 1307776020.01602 # ualarm(100000) # t1 = 1307776020.11687 # dt = 0.100849151611328 # r = 1.00849151611328 ok 4 # t0 = 1307776020.118 # ualarm(1100000) # t1 = 1307776021.21926 # dt = 1.10125684738159 # r = 1.00114258852872 ok 5 # t0 = 1307776021.22134 # ualarm(2200000) # t1 = 1307776023.42289 # dt = 2.2015540599823 # r = 1.00070639090105 ok 6 # t0 = 1307776023.42617 # ualarm(4300000) # t1 = 1307776027.72763 # dt = 4.3014600276947 # r = 1.00033954132435 ok 7 # t0 = 1307776027.73082 # got0 = 0 # t1 = 1307776028.03083 # t1 - t0 = 0.3000168800354 # got1 = 199976 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 19413, terminating the watchdog process 19414 before it terminates me in 351 seconds (testing took 9 seconds). # kill KILL 19414 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 19415, starting the watchdog process... # I am the watchdog process 19416, sleeping for 360 seconds... # The watchdog process 19416 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19415, terminating the watchdog process 19416 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19416 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.04 usr 0.06 sys + 13.95 cusr 0.41 csys = 14.46 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/fly1400/var/cpan/build/Time-HiRes-1.9724-ByN9yV && tar cvf - Time-HiRes-1.9724.ppd blib) | gzip -c >/home/fly1400/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.bs blib/arch/auto/Time/HiRes/HiRes.so blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1400/var/cpan/build/Time-HiRes-1.9724-ByN9yV/Time-HiRes-1.9724.ppd /home/fly1400/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-11T00:07:13