PATH=/usr/bin:/bin Start 2011-08-10T07:09:27 ActivePerl-1003 CPAN-1.9402 Going to read '/home/cpanfly/var/cpan/Metadata' Database was generated on Tue, 09 Aug 2011 18:27:38 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/cpanfly/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()... 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/cpanfly/ap1003/bin/perl-static /home/cpanfly/var/megalib/ExtUtils/xsubpp -typemap /home/cpanfly/ap1003/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 -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/cpanfly/ap1003/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 -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/cpanfly/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 3150, starting the watchdog process... # I am the watchdog process 3151, sleeping for 360 seconds... # The watchdog process 3151 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.325653 # Select returned! 4 0.325937 # 0.891354333333333 # Tick! 3 0.621036 # Select returned! 3 0.621312 # 0.792896 # Tick! 2 0.920166 # Select returned! 2 0.920453 # 0.693182333333333 # Tick! 1 1.220148 # Select returned! 1 1.220433 # 0.593189 # Tick! 0 1.584071 # Select returned! 0 1.584349 # 0.471883666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1312985375.04976, t0 = 1312985375.04966, dt = 9.70363616943359e-05 # N = 2048, t1 = 1312985375.05012, t0 = 1312985375.04995, dt = 0.000161170959472656 # N = 4096, t1 = 1312985375.05064, t0 = 1312985375.05027, dt = 0.00036311149597168 # N = 8192, t1 = 1312985375.0515, t0 = 1312985375.05079, dt = 0.000712871551513672 # N = 16384, t1 = 1312985375.05279, t0 = 1312985375.05164, dt = 0.00114893913269043 # N = 32768, t1 = 1312985375.05567, t0 = 1312985375.05299, dt = 0.00267601013183594 # N = 65536, t1 = 1312985375.06095, t0 = 1312985375.05584, dt = 0.00511789321899414 # N = 131072, t1 = 1312985375.07236, t0 = 1312985375.0611, dt = 0.0112519264221191 # Alarm 1 - 1312985375.07398 # Alarm 2 - 1312985375.09636 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 3150, terminating the watchdog process 3151 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 3151 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 3152, starting the watchdog process... # I am the watchdog process 3153, sleeping for 360 seconds... # The watchdog process 3153 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 = 1312985380.78413, t0 = 1312985379.26858 # dt = 1.51555490493774, rt = 0.0103699366251628 ok 2 ok 3 ok 4 # skip no clock_nanosleep # clock = 0.03 # clock = 0.03 0.16 # clock = 0.03 0.16 0.28 # clock = 0.03 0.16 0.28 0.4 ok 5 # I am the main process 3152, terminating the watchdog process 3153 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 3153 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 3154, starting the watchdog process... # I am the watchdog process 3155, sleeping for 360 seconds... # The watchdog process 3155 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 3154, terminating the watchdog process 3155 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 3155 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 3156, starting the watchdog process... # I am the watchdog process 3157, sleeping for 360 seconds... # The watchdog process 3157 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.51 0.4 # Tick! 2 0.673897 # Tick! 1 1.133904 # Tick! 0 1.925069 # Tick! 0 2.71489 # getitimer: 0 0 ok 2 # I am the main process 3156, terminating the watchdog process 3157 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 3157 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 3158, starting the watchdog process... # I am the watchdog process 3159, sleeping for 360 seconds... # The watchdog process 3159 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 3158, terminating the watchdog process 3159 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 3159 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 3160, starting the watchdog process... # I am the watchdog process 3161, sleeping for 360 seconds... # The watchdog process 3161 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1312985385.74908 ok 3 # sleep...0.504784 ok 4 # I am the main process 3160, terminating the watchdog process 3161 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 3161 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 3162, starting the watchdog process... # I am the watchdog process 3163, sleeping for 360 seconds... # The watchdog process 3163 launched, continuing testing... # mtime = 1312985386 1312985386 1312985387 1312985387 1312985388 # atime = 1312985386 1312985387 1312985387 1312985387 1312985388 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 3162, terminating the watchdog process 3163 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 3163 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 3164, starting the watchdog process... # I am the watchdog process 3165, sleeping for 360 seconds... # The watchdog process 3165 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 20.7872889041901, n = 100, s/n = 0.207872889041901 # I am the main process 3164, terminating the watchdog process 3165 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 3165 = 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 3167, starting the watchdog process... # I am the watchdog process 3168, sleeping for 360 seconds... # The watchdog process 3168 launched, continuing testing... ok 1 # tick = 1, one = 1312985388, two = 1312985388, three = 1312985388 ok 2 # tick = 3, one = 1312985388, two = 1312985388, three = 1312985388 ok 3 - negative time error # t0 = 1312985388.31913 # ualarm(100000) # t1 = 1312985388.44336 # dt = 0.124229907989502 # r = 1.24229907989502 ok 4 # t0 = 1312985388.44688 # ualarm(1100000) # t1 = 1312985389.54431 # dt = 1.09742784500122 # r = 0.997661677273837 ok 5 # t0 = 1312985389.54538 # ualarm(2200000) # t1 = 1312985391.74445 # dt = 2.19907689094543 # r = 0.999580404975197 ok 6 # t0 = 1312985391.74568 # ualarm(4300000) # t1 = 1312985396.04662 # dt = 4.30094408988953 # r = 1.00021955578826 ok 7 # t0 = 1312985396.04776 # got0 = 0 # t1 = 1312985396.34776 # t1 - t0 = 0.30000114440918 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 3167, terminating the watchdog process 3168 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 3168 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 3169, starting the watchdog process... # I am the watchdog process 3170, sleeping for 360 seconds... # The watchdog process 3170 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 3169, terminating the watchdog process 3170 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 3170 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.03 usr 0.02 sys + 9.18 cusr 3.71 csys = 12.94 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/cpanfly/var/cpan/build/Time-HiRes-1.9724-NgB4mB && tar cvf - Time-HiRes-1.9724.ppd blib) | gzip -c >/home/cpanfly/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/cpanfly/var/cpan/build/Time-HiRes-1.9724-NgB4mB/Time-HiRes-1.9724.ppd /home/cpanfly/var/REPO/Z/ZE/ZEFRAM Finished 2011-08-10T07:10:01