PATH=/usr/bin:/bin Start 2011-06-09T00:11:09 ActivePerl-1400 CPAN-1.9402 Going to read '/home/fly1400/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 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/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.9723\" -DXS_VERSION=\"1.9723\" -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.9723.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 19398, starting the watchdog process... # I am the watchdog process 19399, sleeping for 360 seconds... # The watchdog process 19399 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.317234 # Select returned! 4 0.31783 # 0.894056666666667 # Tick! 3 0.620033 # Select returned! 3 0.620838 # 0.793054 # Tick! 2 0.923125 # Select returned! 2 0.923503 # 0.692165666666667 # Tick! 1 1.22525 # Select returned! 1 1.225861 # 0.591379666666667 # Tick! 0 1.528296 # Select returned! 0 1.528895 # 0.490368333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307603475.64726, t0 = 1307603475.64701, dt = 0.000242948532104492 # N = 2048, t1 = 1307603475.64758, t0 = 1307603475.64741, dt = 0.000171899795532227 # N = 4096, t1 = 1307603475.64815, t0 = 1307603475.6477, dt = 0.000457048416137695 # N = 8192, t1 = 1307603475.64898, t0 = 1307603475.64815, dt = 0.000823974609375 # N = 16384, t1 = 1307603475.65021, t0 = 1307603475.64913, dt = 0.00108098983764648 # N = 32768, t1 = 1307603475.65454, t0 = 1307603475.65049, dt = 0.00404715538024902 # N = 65536, t1 = 1307603475.66132, t0 = 1307603475.65536, dt = 0.00596499443054199 # N = 131072, t1 = 1307603475.67125, t0 = 1307603475.6623, dt = 0.00895500183105469 # N = 262144, t1 = 1307603475.68529, t0 = 1307603475.67217, dt = 0.0131101608276367 # Alarm 1 - 1307603475.69754 # Alarm 2 - 1307603475.72613 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 19398, terminating the watchdog process 19399 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 19399 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 19400, starting the watchdog process... # The watchdog process 19401 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 # I am the watchdog process 19401, sleeping for 360 seconds... # t1 = 1307603481.41676, t0 = 1307603479.91477 # dt = 1.50199699401855, rt = 0.00133132934570312 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 19400, terminating the watchdog process 19401 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19401 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 19402, starting the watchdog process... # I am the watchdog process 19403, sleeping for 360 seconds... # The watchdog process 19403 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19402, terminating the watchdog process 19403 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19403 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 19404, starting the watchdog process... # I am the watchdog process 19405, sleeping for 360 seconds... # The watchdog process 19405 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.501923 0.400939 # Tick! 2 0.516258 # Tick! 1 0.920055 # Tick! 0 1.336181 # Tick! 0 1.741919 # getitimer: 0 0 ok 2 # I am the main process 19404, terminating the watchdog process 19405 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19405 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 19406, starting the watchdog process... # I am the watchdog process 19407, sleeping for 360 seconds... # The watchdog process 19407 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 19406, terminating the watchdog process 19407 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19407 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 19408, starting the watchdog process... # I am the watchdog process 19409, sleeping for 360 seconds... # The watchdog process 19409 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307603486.50455 ok 3 # sleep...0.501707 ok 4 # I am the main process 19408, terminating the watchdog process 19409 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19409 = 1 # All done. ok t/stat.t .......... 1..1 # 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... # mtime = 1307603487 1307603487 1307603487 1307603488 1307603488 # atime = 1307603487 1307603487 1307603487 1307603488 1307603488 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 19410, terminating the watchdog process 19411 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19411 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 19416, starting the watchdog process... # I am the watchdog process 19418, sleeping for 360 seconds... # The watchdog process 19418 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 55.4923150539398, n = 100, s/n = 0.554923150539398 # I am the main process 19416, terminating the watchdog process 19418 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19418 = 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 19421, starting the watchdog process... # I am the watchdog process 19422, sleeping for 360 seconds... # The watchdog process 19422 launched, continuing testing... ok 1 # tick = 1, one = 1307603488, two = 1307603488, three = 1307603488 ok 2 # tick = 3, one = 1307603488, two = 1307603488, three = 1307603488 ok 3 - negative time error # t0 = 1307603488.67245 # ualarm(100000) # t1 = 1307603488.77334 # dt = 0.100890159606934 # r = 1.00890159606934 ok 4 # t0 = 1307603488.77553 # ualarm(1100000) # t1 = 1307603489.87606 # dt = 1.10052490234375 # r = 1.00047718394886 ok 5 # t0 = 1307603489.87725 # ualarm(2200000) # t1 = 1307603492.08011 # dt = 2.2028591632843 # r = 1.00129961967468 ok 6 # t0 = 1307603492.08186 # ualarm(4300000) # t1 = 1307603496.38461 # dt = 4.30275201797485 # r = 1.0006400041802 ok 7 # t0 = 1307603496.38696 # got0 = 0 # t1 = 1307603496.68696 # t1 - t0 = 0.30000114440918 # got1 = 200004 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 19421, terminating the watchdog process 19422 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 19422 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 19448, starting the watchdog process... # I am the watchdog process 19449, sleeping for 360 seconds... # The watchdog process 19449 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19448, terminating the watchdog process 19449 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19449 = 1 # All done. ok All tests successful. Files=11, Tests=53, 26 wallclock secs ( 0.05 usr 0.05 sys + 13.96 cusr 0.40 csys = 14.46 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/fly1400/var/cpan/build/Time-HiRes-1.9723-4Csclo && tar cvf - Time-HiRes-1.9723.ppd blib) | gzip -c >/home/fly1400/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.bs blib/arch/auto/Time/HiRes/HiRes.so blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1400/var/cpan/build/Time-HiRes-1.9723-4Csclo/Time-HiRes-1.9723.ppd /home/fly1400/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-09T00:11:42