PATH=/usr/bin:/bin Start 2011-06-10T18:06:22 ActivePerl-1003 CPAN-1.9402 Going to read '/home/fly1003/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 Will not use Archive::Tar, need 1.00 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/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.9724\" -DXS_VERSION=\"1.9724\" -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.9724.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 2260, starting the watchdog process... # I am the watchdog process 2261, sleeping for 360 seconds... # The watchdog process 2261 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.311081 # Select returned! 4 0.311598 # 0.896134 # Tick! 3 0.613041 # Select returned! 3 0.61343 # 0.795523333333333 # Tick! 2 0.915258 # Select returned! 2 0.916011 # 0.694663 # Tick! 1 1.218207 # Select returned! 1 1.218699 # 0.593767 # Tick! 0 1.520264 # Select returned! 0 1.520692 # 0.493102666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307754389.20904, t0 = 1307754389.20848, dt = 0.00055694580078125 # N = 2048, t1 = 1307754389.20952, t0 = 1307754389.20926, dt = 0.000257015228271484 # N = 4096, t1 = 1307754389.21009, t0 = 1307754389.20969, dt = 0.00040888786315918 # N = 8192, t1 = 1307754389.21056, t0 = 1307754389.21017, dt = 0.000384092330932617 # N = 16384, t1 = 1307754389.21206, t0 = 1307754389.21077, dt = 0.00128316879272461 # N = 32768, t1 = 1307754389.21447, t0 = 1307754389.21228, dt = 0.00218510627746582 # N = 65536, t1 = 1307754389.22252, t0 = 1307754389.21724, dt = 0.00528311729431152 # N = 131072, t1 = 1307754389.22952, t0 = 1307754389.22352, dt = 0.00599789619445801 # N = 262144, t1 = 1307754389.24447, t0 = 1307754389.23052, dt = 0.0139529705047607 # Alarm 1 - 1307754389.25729 # Alarm 2 - 1307754389.29249 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 2260, terminating the watchdog process 2261 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 2261 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 2263, starting the watchdog process... # I am the watchdog process 2264, sleeping for 360 seconds... # The watchdog process 2264 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 = 1307754394.99165, t0 = 1307754393.49045 # dt = 1.5011990070343, rt = 0.000799338022867913 ok 2 ok 3 ok 4 # clock = 0.01 # clock = 0.01 0.07 # clock = 0.01 0.07 0.13 # clock = 0.01 0.07 0.13 0.18 ok 5 # I am the main process 2263, terminating the watchdog process 2264 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 2264 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 2266, starting the watchdog process... # I am the watchdog process 2267, sleeping for 360 seconds... # The watchdog process 2267 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 2266, terminating the watchdog process 2267 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2267 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 2270, starting the watchdog process... # I am the watchdog process 2271, sleeping for 360 seconds... # The watchdog process 2271 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.501923 0.400939 # Tick! 2 0.517212 # Tick! 1 0.927237 # Tick! 0 1.331934 # Tick! 0 1.734966 # getitimer: 0 0 ok 2 # I am the main process 2270, terminating the watchdog process 2271 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 2271 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 2272, starting the watchdog process... # I am the watchdog process 2273, sleeping for 360 seconds... # The watchdog process 2273 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 2272, terminating the watchdog process 2273 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 2273 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 2274, starting the watchdog process... # I am the watchdog process 2275, sleeping for 360 seconds... # The watchdog process 2275 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307754400.08129 ok 3 # sleep...0.501916 ok 4 # I am the main process 2274, terminating the watchdog process 2275 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 2275 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 2276, starting the watchdog process... # I am the watchdog process 2277, sleeping for 360 seconds... # The watchdog process 2277 launched, continuing testing... # mtime = 1307754400 1307754401 1307754401 1307754401 1307754401 # atime = 1307754400 1307754401 1307754401 1307754401 1307754402 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 2276, terminating the watchdog process 2277 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 2277 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 2278, starting the watchdog process... # I am the watchdog process 2279, sleeping for 360 seconds... # The watchdog process 2279 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 7.00509762763977, n = 100, s/n = 0.0700509762763977 # I am the main process 2278, terminating the watchdog process 2279 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 2279 = 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 2281, starting the watchdog process... # I am the watchdog process 2282, sleeping for 360 seconds... # The watchdog process 2282 launched, continuing testing... ok 1 # tick = 1, one = 1307754402, two = 1307754402, three = 1307754402 ok 2 # tick = 3, one = 1307754402, two = 1307754402, three = 1307754402 ok 3 - negative time error # t0 = 1307754402.18116 # ualarm(100000) # t1 = 1307754402.28294 # dt = 0.101775884628296 # r = 1.01775884628296 ok 4 # t0 = 1307754402.28598 # ualarm(1100000) # t1 = 1307754403.38755 # dt = 1.10156297683716 # r = 1.00142088803378 ok 5 # t0 = 1307754403.3884 # ualarm(2200000) # t1 = 1307754405.5921 # dt = 2.20370888710022 # r = 1.00168585777283 ok 6 # t0 = 1307754405.59493 # ualarm(4300000) # t1 = 1307754409.89847 # dt = 4.30353617668152 # r = 1.00082236667012 ok 7 # t0 = 1307754409.9014 # got0 = 0 # t1 = 1307754410.2014 # t1 - t0 = 0.300001859664917 # got1 = 200002 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 2281, terminating the watchdog process 2282 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 2282 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 2284, starting the watchdog process... # I am the watchdog process 2285, sleeping for 360 seconds... # The watchdog process 2285 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 2284, terminating the watchdog process 2285 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 2285 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.06 usr 0.05 sys + 13.93 cusr 0.52 csys = 14.56 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/fly1003/var/cpan/build/Time-HiRes-1.9724-vcME2A && tar cvf - Time-HiRes-1.9724.ppd blib) | gzip -c >/home/fly1003/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/fly1003/var/cpan/build/Time-HiRes-1.9724-vcME2A/Time-HiRes-1.9724.ppd /home/fly1003/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-10T18:06:54