PATH=/usr/bin:/bin Start 2011-06-08T21:17:35 ActivePerl-1200 CPAN-1.9402 Going to read '/home/fly1200/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/fly1200/ap1200-292396/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/fly1200/ap1200-292396/bin/perl-static /home/fly1200/var/megalib/ExtUtils/xsubpp -typemap /home/fly1200/ap1200-292396/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DUSE_SITECUSTOMIZE -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/fly1200/ap1200-292396/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/fly1200/ap1200-292396/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 6798, starting the watchdog process... # I am the watchdog process 6799, sleeping for 360 seconds... # The watchdog process 6799 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.314631 # Select returned! 4 0.314835 # 0.895055 # Tick! 3 0.615656 # Select returned! 3 0.615921 # 0.794693 # Tick! 2 0.916782 # Select returned! 2 0.916977 # 0.694341 # Tick! 1 1.217862 # Select returned! 1 1.218052 # 0.593982666666667 # Tick! 0 1.519806 # Select returned! 0 1.520138 # 0.493287333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1307593062.14767, t0 = 1307593062.14746, dt = 0.000211000442504883 # N = 2048, t1 = 1307593062.14812, t0 = 1307593062.14794, dt = 0.000171184539794922 # N = 4096, t1 = 1307593062.14837, t0 = 1307593062.14819, dt = 0.000181913375854492 # N = 8192, t1 = 1307593062.14889, t0 = 1307593062.14842, dt = 0.000473976135253906 # N = 16384, t1 = 1307593062.14957, t0 = 1307593062.14891, dt = 0.000656843185424805 # N = 32768, t1 = 1307593062.1506, t0 = 1307593062.1497, dt = 0.000900030136108398 # N = 65536, t1 = 1307593062.156, t0 = 1307593062.15129, dt = 0.00471591949462891 # N = 131072, t1 = 1307593062.16323, t0 = 1307593062.15684, dt = 0.0063929557800293 # N = 262144, t1 = 1307593062.17913, t0 = 1307593062.164, dt = 0.0151360034942627 # Alarm 1 - 1307593062.1916 # Alarm 2 - 1307593062.22423 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 6798, terminating the watchdog process 6799 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 6799 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 6800, starting the watchdog process... # I am the watchdog process 6801, sleeping for 360 seconds... # The watchdog process 6801 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 = 1307593067.9453, t0 = 1307593066.44088 # dt = 1.5044219493866, rt = 0.00294796625773119 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.19 ok 5 # I am the main process 6800, terminating the watchdog process 6801 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 6801 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 6802, starting the watchdog process... # I am the watchdog process 6803, sleeping for 360 seconds... # The watchdog process 6803 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 6802, terminating the watchdog process 6803 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 6803 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 6804, starting the watchdog process... # I am the watchdog process 6805, sleeping for 360 seconds... # The watchdog process 6805 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.518373 # Tick! 1 0.932524 # Tick! 0 1.374605 # Tick! 0 1.819568 # getitimer: 0 0 ok 2 # I am the main process 6804, terminating the watchdog process 6805 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 6805 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 6807, starting the watchdog process... # I am the watchdog process 6808, sleeping for 360 seconds... # The watchdog process 6808 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 6807, terminating the watchdog process 6808 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 6808 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 6809, starting the watchdog process... # I am the watchdog process 6810, sleeping for 360 seconds... # The watchdog process 6810 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1307593073.10286 ok 3 # sleep...0.500612 ok 4 # I am the main process 6809, terminating the watchdog process 6810 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 6810 = 1 # All done. ok t/stat.t .......... 1..1 # I am the main process 6811, starting the watchdog process... # I am the watchdog process 6812, sleeping for 360 seconds... # The watchdog process 6812 launched, continuing testing... # mtime = 1307593073 1307593074 1307593074 1307593074 1307593075 # atime = 1307593073 1307593074 1307593074 1307593074 1307593075 # ai = 4, mi = 4, ss = 0 ok 1 # skip no subsecond timestamps detected # I am the main process 6811, terminating the watchdog process 6812 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 6812 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 6813, starting the watchdog process... # I am the watchdog process 6814, sleeping for 360 seconds... # The watchdog process 6814 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 16.7974863052368, n = 100, s/n = 0.167974863052368 # I am the main process 6813, terminating the watchdog process 6814 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 6814 = 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 6816, starting the watchdog process... # I am the watchdog process 6817, sleeping for 360 seconds... # The watchdog process 6817 launched, continuing testing... ok 1 # tick = 1, one = 1307593075, two = 1307593075, three = 1307593075 ok 2 # tick = 3, one = 1307593075, two = 1307593075, three = 1307593075 ok 3 - negative time error # t0 = 1307593075.2869 # ualarm(100000) # t1 = 1307593075.38885 # dt = 0.101948976516724 # r = 1.01948976516724 ok 4 # t0 = 1307593075.39192 # ualarm(1100000) # t1 = 1307593076.49358 # dt = 1.10166597366333 # r = 1.00151452151212 ok 5 # t0 = 1307593076.49472 # ualarm(2200000) # t1 = 1307593078.69652 # dt = 2.20179605484009 # r = 1.00081638856368 ok 6 # t0 = 1307593078.69806 # ualarm(4300000) # t1 = 1307593082.99982 # dt = 4.30176115036011 # r = 1.00040956985119 ok 7 # t0 = 1307593083.00146 # got0 = 0 # t1 = 1307593083.30149 # t1 - t0 = 0.300029993057251 # got1 = 199978 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 6816, terminating the watchdog process 6817 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 6817 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 6818, starting the watchdog process... # I am the watchdog process 6819, sleeping for 360 seconds... # The watchdog process 6819 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 6818, terminating the watchdog process 6819 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 6819 = 1 # All done. ok All tests successful. Files=11, Tests=53, 27 wallclock secs ( 0.05 usr 0.04 sys + 14.04 cusr 0.48 csys = 14.61 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9723.tar.gz make test TEST_VERBOSE=1 -- OK Could not find '/net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/CHECKSUMS' Fetching with LWP: file:///net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/CHECKSUMS Andrew Main (Zefram) <zefram@fysh.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1200/var/cpan/build/Time-HiRes-1.9723-FM7DWY && tar cvf - Time-HiRes-1.9723.ppd blib) | gzip -c >/home/fly1200/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.so blib/arch/auto/Time/HiRes/HiRes.bs blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1200/var/cpan/build/Time-HiRes-1.9723-FM7DWY/Time-HiRes-1.9723.ppd /home/fly1200/var/REPO/Z/ZE/ZEFRAM Finished 2011-06-08T21:18:10