PATH=/home/fly1600/bin:/usr/kerberos/bin:/usr/local/bin:/bin:/usr/bin Start 2012-06-04T15:45:57 ActivePerl-1600 CPAN-1.9402 Going to read '/home/fly1600/var/cpan/Metadata' Database was generated on Mon, 04 Jun 2012 22:15:33 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 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/fly1600/ap1600/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 and MYMETA.json Now you may issue 'make'. Do not forget also 'make test'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm /home/fly1600/ap1600/bin/perl-static /home/fly1600/ap1600/lib/ExtUtils/xsubpp -typemap /home/fly1600/ap1600/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.9725\" -DXS_VERSION=\"1.9725\" -fPIC "-I/home/fly1600/ap1600/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.9725.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly1600/ap1600/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 13677, starting the watchdog process... # I am the watchdog process 13678, sleeping for 360 seconds... # The watchdog process 13678 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.302359 # Select returned! 4 0.30287 # 0.899043333333333 # Tick! 3 0.604878 # Select returned! 3 0.605021 # 0.798326333333333 # Tick! 2 0.907014 # Select returned! 2 0.907542 # 0.697486 # Tick! 1 1.210069 # Select returned! 1 1.210725 # 0.596425 # Tick! 0 1.513023 # Select returned! 0 1.51342 # 0.495526666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1338849964.45748, t0 = 1338849964.45687, dt = 0.000615119934082031 # N = 2048, t1 = 1338849964.45793, t0 = 1338849964.45778, dt = 0.000149965286254883 # N = 4096, t1 = 1338849964.45884, t0 = 1338849964.45819, dt = 0.000646114349365234 # N = 8192, t1 = 1338849964.4596, t0 = 1338849964.45892, dt = 0.000685930252075195 # N = 16384, t1 = 1338849964.461, t0 = 1338849964.45977, dt = 0.00123190879821777 # N = 32768, t1 = 1338849964.464, t0 = 1338849964.46169, dt = 0.00231409072875977 # N = 65536, t1 = 1338849964.472, t0 = 1338849964.465, dt = 0.00699520111083984 # N = 131072, t1 = 1338849964.48099, t0 = 1338849964.473, dt = 0.0079958438873291 # N = 262144, t1 = 1338849964.49983, t0 = 1338849964.48199, dt = 0.0178401470184326 # Alarm 1 - 1338849964.51297 # Alarm 2 - 1338849964.54774 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 13677, terminating the watchdog process 13678 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 13678 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 13679, starting the watchdog process... # I am the watchdog process 13680, sleeping for 360 seconds... # The watchdog process 13680 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 = 1338849970.28264, t0 = 1338849968.77996 # dt = 1.502681016922, rt = 0.00178734461466479 ok 2 ok 3 ok 4 # clock = 0.02 # clock = 0.02 0.09 # clock = 0.02 0.09 0.16 # clock = 0.02 0.09 0.16 0.22 ok 5 # I am the main process 13679, terminating the watchdog process 13680 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 13680 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 13681, starting the watchdog process... # I am the watchdog process 13682, sleeping for 360 seconds... # The watchdog process 13682 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 13681, terminating the watchdog process 13682 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13682 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 13684, starting the watchdog process... # The watchdog process 13685 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # I am the watchdog process 13685, sleeping for 360 seconds... # Tick! 2 0.511444 # Tick! 1 0.934536 # Tick! 0 1.359595 # Tick! 0 1.81683 # getitimer: 0 0 ok 2 # I am the main process 13684, terminating the watchdog process 13685 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13685 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 13686, starting the watchdog process... # I am the watchdog process 13687, sleeping for 360 seconds... # The watchdog process 13687 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 13686, terminating the watchdog process 13687 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13687 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 13688, starting the watchdog process... # I am the watchdog process 13689, sleeping for 360 seconds... # The watchdog process 13689 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1338849975.49023 ok 3 # sleep...0.502263 ok 4 # I am the main process 13688, terminating the watchdog process 13689 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 13689 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 13690, starting the watchdog process... # I am the watchdog process 13691, sleeping for 360 seconds... # The watchdog process 13691 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 = 1338849976 1338849976 1338849976 1338849977 1338849977 # atime = 1338849976 1338849976 1338849976 1338849977 1338849977 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 13690, terminating the watchdog process 13691 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13691 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 13692, starting the watchdog process... # I am the watchdog process 13693, sleeping for 360 seconds... # The watchdog process 13693 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 56.8562474250793, n = 100, s/n = 0.568562474250793 # I am the main process 13692, terminating the watchdog process 13693 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 13693 = 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 13695, starting the watchdog process... # I am the watchdog process 13696, sleeping for 360 seconds... # The watchdog process 13696 launched, continuing testing... ok 1 # tick = 1, one = 1338849977, two = 1338849977, three = 1338849977 ok 2 # tick = 3, one = 1338849977, two = 1338849977, three = 1338849977 ok 3 - negative time error # t0 = 1338849977.68417 # ualarm(100000) # t1 = 1338849977.78772 # dt = 0.103547096252441 # r = 1.03547096252441 ok 4 # t0 = 1338849977.79038 # ualarm(1100000) # t1 = 1338849978.89263 # dt = 1.10225510597229 # r = 1.00205009633845 ok 5 # t0 = 1338849978.89633 # ualarm(2200000) # t1 = 1338849981.09906 # dt = 2.20272517204285 # r = 1.00123871456493 ok 6 # t0 = 1338849981.10107 # ualarm(4300000) # t1 = 1338849985.40458 # dt = 4.30351281166077 # r = 1.00081693294436 ok 7 # t0 = 1338849985.40742 # got0 = 0 # t1 = 1338849985.70743 # t1 - t0 = 0.30000114440918 # got1 = 200003 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 13695, terminating the watchdog process 13696 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 13696 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 13697, starting the watchdog process... # I am the watchdog process 13698, sleeping for 360 seconds... # The watchdog process 13698 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 13697, terminating the watchdog process 13698 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 13698 = 1 # All done. ok All tests successful. Files=11, Tests=68, 27 wallclock secs ( 0.05 usr 0.06 sys + 14.01 cusr 0.56 csys = 14.68 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/fly1600/var/cpan/build/Time-HiRes-1.9725-Mt0dAT && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/home/fly1600/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.bs blib/arch/auto/Time/HiRes/HiRes.so blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1600/var/cpan/build/Time-HiRes-1.9725-Mt0dAT/Time-HiRes-1.9725.ppd /home/fly1600/var/REPO/Z/ZE/ZEFRAM Finished 2012-06-04T15:46:32