PATH=/usr/bin:/bin Start 2012-02-01T16:10:07 ActivePerl-818 CPAN-1.9402 Going to read '/home/fly818/var/cpan/Metadata' Database was generated on Wed, 01 Feb 2012 23:14:00 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz ok Will not use Archive::Tar, need 1.00 Time-HiRes-1.9725/ Time-HiRes-1.9725/META.yml Time-HiRes-1.9725/MANIFEST Time-HiRes-1.9725/HiRes.pm Time-HiRes-1.9725/Changes Time-HiRes-1.9725/ppport.h Time-HiRes-1.9725/README Time-HiRes-1.9725/hints/ Time-HiRes-1.9725/hints/aix.pl Time-HiRes-1.9725/hints/dynixptx.pl Time-HiRes-1.9725/hints/solaris.pl Time-HiRes-1.9725/hints/linux.pl Time-HiRes-1.9725/hints/irix.pl Time-HiRes-1.9725/hints/dec_osf.pl Time-HiRes-1.9725/hints/svr4.pl Time-HiRes-1.9725/hints/sco.pl Time-HiRes-1.9725/TODO Time-HiRes-1.9725/HiRes.xs Time-HiRes-1.9725/fallback/ Time-HiRes-1.9725/fallback/const-c.inc Time-HiRes-1.9725/fallback/const-xs.inc Time-HiRes-1.9725/typemap Time-HiRes-1.9725/Makefile.PL Time-HiRes-1.9725/META.json Time-HiRes-1.9725/t/ Time-HiRes-1.9725/t/Watchdog.pm Time-HiRes-1.9725/t/sleep.t Time-HiRes-1.9725/t/nanosleep.t Time-HiRes-1.9725/t/itimer.t Time-HiRes-1.9725/t/usleep.t Time-HiRes-1.9725/t/ualarm.t Time-HiRes-1.9725/t/clock.t Time-HiRes-1.9725/t/gettimeofday.t Time-HiRes-1.9725/t/time.t Time-HiRes-1.9725/t/tv_interval.t Time-HiRes-1.9725/t/stat.t Time-HiRes-1.9725/t/alarm.t CPAN.pm: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz >>> /home/fly818/ap818/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()... NOT 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 and MYMETA.json Now you may issue 'make'. Do not forget also 'make test'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm /home/fly818/ap818/bin/perl-static /home/fly818/var/megalib/ExtUtils/xsubpp -typemap /home/fly818/ap818/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DTHREADS_HAVE_PIDS -DUSE_SITECUSTOMIZE -DNO_HASH_SEED -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -I/usr/include/gdbm -O2 -DVERSION=\"1.9725\" -DXS_VERSION=\"1.9725\" -fpic "-I/home/fly818/ap818/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -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="/lib" gcc -shared 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.9725.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly818/ap818/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 11141, starting the watchdog process... # I am the watchdog process 11146, sleeping for 360 seconds... # The watchdog process 11146 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.412206 # Select returned! 4 0.412206 # 0.862598 # Tick! 3 0.773545 # Select returned! 3 0.773781 # 0.742073 # Tick! 2 1.073613 # Select returned! 2 1.073898 # 0.642034 # Tick! 1 1.372476 # Select returned! 1 1.372763 # 0.542412333333333 # Tick! 0 1.672491 # Select returned! 0 1.672726 # 0.442424666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1328141419.09989, t0 = 1328141419.0998, dt = 9.70363616943359e-05 # N = 2048, t1 = 1328141419.10023, t0 = 1328141419.10005, dt = 0.000177860260009766 # N = 4096, t1 = 1328141419.10077, t0 = 1328141419.10039, dt = 0.000370979309082031 # N = 8192, t1 = 1328141419.10167, t0 = 1328141419.10093, dt = 0.00074005126953125 # N = 16384, t1 = 1328141419.10327, t0 = 1328141419.10182, dt = 0.00144505500793457 # N = 32768, t1 = 1328141419.10634, t0 = 1328141419.10346, dt = 0.00288105010986328 # N = 65536, t1 = 1328141419.11201, t0 = 1328141419.10651, dt = 0.00549602508544922 # N = 131072, t1 = 1328141419.18473, t0 = 1328141419.1122, dt = 0.0725290775299072 # Alarm 1 - 1328141419.18818 # Alarm 2 - 1328141419.2116 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 11141, terminating the watchdog process 11146 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 11146 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 11214, starting the watchdog process... # I am the watchdog process 11215, sleeping for 360 seconds... # The watchdog process 11215 launched, continuing testing... ok 1 - require Time::HiRes; # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 0 # have_clock = 1 # CLOCK_REALTIME: try = 1 # t1 = 1328141425.02936, t0 = 1328141423.51117 # dt = 1.51818585395813, rt = 0.0121239026387532 ok 2 ok 3 ok 4 # skip no clock_nanosleep # clock = 0.04 # clock = 0.04 0.1 # clock = 0.04 0.1 0.17 # clock = 0.04 0.1 0.17 0.23 ok 5 # I am the main process 11214, terminating the watchdog process 11215 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 11215 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 11219, starting the watchdog process... # I am the watchdog process 11220, sleeping for 360 seconds... # The watchdog process 11220 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 11219, terminating the watchdog process 11220 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 11220 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 11224, starting the watchdog process... # I am the watchdog process 11225, sleeping for 360 seconds... # The watchdog process 11225 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.51 0.4 # Tick! 2 0.948075 # Tick! 1 1.718277 # Tick! 0 2.418408 # Tick! 0 3.128924 # getitimer: 0 0 ok 2 # I am the main process 11224, terminating the watchdog process 11225 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 11225 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 11280, starting the watchdog process... # I am the watchdog process 11281, sleeping for 360 seconds... # The watchdog process 11281 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 11280, terminating the watchdog process 11281 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 11281 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 11285, starting the watchdog process... # I am the watchdog process 11287, sleeping for 360 seconds... # The watchdog process 11287 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1328141430.15448 ok 3 # sleep...0.516362 ok 4 # I am the main process 11285, terminating the watchdog process 11287 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 11287 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 11294, starting the watchdog process... # I am the watchdog process 11295, sleeping for 360 seconds... # The watchdog process 11295 launched, continuing testing... 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 # mtime = 1328141431 1328141431 1328141432 1328141432 1328141432 # atime = 1328141431 1328141431 1328141432 1328141432 1328141432 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 11294, terminating the watchdog process 11295 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 11295 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 11305, starting the watchdog process... # I am the watchdog process 11306, sleeping for 360 seconds... # The watchdog process 11306 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 5.15903091430664, n = 100, s/n = 0.0515903091430664 # I am the main process 11305, terminating the watchdog process 11306 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 11306 = 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 11308, starting the watchdog process... # I am the watchdog process 11309, sleeping for 360 seconds... # The watchdog process 11309 launched, continuing testing... ok 1 # tick = 1, one = 1328141433, two = 1328141433, three = 1328141433 ok 2 # tick = 3, one = 1328141433, two = 1328141433, three = 1328141433 ok 3 - negative time error # t0 = 1328141433.30152 # ualarm(100000) # t1 = 1328141433.44096 # dt = 0.139447927474976 # r = 1.39447927474976 ok 4 # t0 = 1328141433.44165 # ualarm(1100000) # t1 = 1328141434.54216 # dt = 1.10050511360168 # r = 1.00045919418335 ok 5 # t0 = 1328141434.54307 # ualarm(2200000) # t1 = 1328141436.79256 # dt = 2.24949097633362 # r = 1.02249589833346 ok 6 # t0 = 1328141436.79363 # ualarm(4300000) # t1 = 1328141441.14254 # dt = 4.34891295433044 # r = 1.01137510565824 ok 7 # t0 = 1328141441.14342 # got0 = 0 # t1 = 1328141441.44342 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 11308, terminating the watchdog process 11309 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 11309 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 11447, starting the watchdog process... # I am the watchdog process 11450, sleeping for 360 seconds... # The watchdog process 11450 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 11447, terminating the watchdog process 11450 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 11450 = 1 # All done. ok All tests successful. Files=11, Tests=68, 28 wallclock secs ( 0.06 usr 0.00 sys + 8.81 cusr 0.91 csys = 9.78 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9725.tar.gz make test TEST_VERBOSE=1 -- OK Andrew Main (Zefram) <zefram@fysh.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly818/var/cpan/build/Time-HiRes-1.9725-vRLZ41 && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/home/fly818/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Time-HiRes-1.9725.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/fly818/var/cpan/build/Time-HiRes-1.9725-vRLZ41/Time-HiRes-1.9725.ppd /home/fly818/var/REPO/Z/ZE/ZEFRAM Finished 2012-02-01T16:10:46