PATH=/usr/bin:/bin:/home/fly1600/var/megalib/bin Start 2013-08-19T14:23:16 ActivePerl-1600 CPAN-2.00 Reading '/home/fly1600/var/cpan/Metadata' Database was generated on Mon, 19 Aug 2013 19:53:02 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz ok Time-HiRes-1.9726/ Time-HiRes-1.9726/HiRes.pm Time-HiRes-1.9726/MANIFEST Time-HiRes-1.9726/Makefile.PL Time-HiRes-1.9726/HiRes.xs Time-HiRes-1.9726/META.json Time-HiRes-1.9726/META.yml Time-HiRes-1.9726/README Time-HiRes-1.9726/TODO Time-HiRes-1.9726/Changes Time-HiRes-1.9726/fallback/ Time-HiRes-1.9726/fallback/const-xs.inc Time-HiRes-1.9726/fallback/const-c.inc Time-HiRes-1.9726/t/ Time-HiRes-1.9726/t/usleep.t Time-HiRes-1.9726/t/time.t Time-HiRes-1.9726/t/itimer.t Time-HiRes-1.9726/t/stat.t Time-HiRes-1.9726/t/Watchdog.pm Time-HiRes-1.9726/t/tv_interval.t Time-HiRes-1.9726/t/gettimeofday.t Time-HiRes-1.9726/t/sleep.t Time-HiRes-1.9726/t/clock.t Time-HiRes-1.9726/t/alarm.t Time-HiRes-1.9726/t/nanosleep.t Time-HiRes-1.9726/t/ualarm.t Time-HiRes-1.9726/hints/ Time-HiRes-1.9726/hints/sco.pl Time-HiRes-1.9726/hints/dec_osf.pl Time-HiRes-1.9726/hints/aix.pl Time-HiRes-1.9726/hints/irix.pl Time-HiRes-1.9726/hints/svr4.pl Time-HiRes-1.9726/hints/linux.pl Time-HiRes-1.9726/hints/dynixptx.pl Time-HiRes-1.9726/hints/solaris.pl Time-HiRes-1.9726/ppport.h Time-HiRes-1.9726/typemap CPAN.pm: Building Z/ZE/ZEFRAM/Time-HiRes-1.9726.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/var/megalib/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.9726\" -DXS_VERSION=\"1.9726\" -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.9726.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 19387, starting the watchdog process... # I am the watchdog process 19388, sleeping for 360 seconds... # The watchdog process 19388 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.301151 # Select returned! 4 0.301302 # 0.899566 # Tick! 3 0.60217 # Select returned! 3 0.602259 # 0.799247 # Tick! 2 0.903214 # Select returned! 2 0.903304 # 0.698898666666667 # Tick! 1 1.203537 # Select returned! 1 1.203618 # 0.598794 # Tick! 0 1.505267 # Select returned! 0 1.505349 # 0.498217 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1376947405.47598, t0 = 1376947405.4759, dt = 8.41617584228516e-05 # N = 2048, t1 = 1376947405.47611, t0 = 1376947405.47606, dt = 4.69684600830078e-05 # N = 4096, t1 = 1376947405.4765, t0 = 1376947405.47622, dt = 0.000287055969238281 # N = 8192, t1 = 1376947405.47713, t0 = 1376947405.47658, dt = 0.000557899475097656 # N = 16384, t1 = 1376947405.47868, t0 = 1376947405.47798, dt = 0.000705957412719727 # N = 32768, t1 = 1376947405.48128, t0 = 1376947405.47876, dt = 0.00251913070678711 # N = 65536, t1 = 1376947405.48718, t0 = 1376947405.48135, dt = 0.00583600997924805 # N = 131072, t1 = 1376947405.4991, t0 = 1376947405.48728, dt = 0.0118160247802734 # Alarm 1 - 1376947405.51017 # Alarm 2 - 1376947405.53055 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 19387, terminating the watchdog process 19388 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 19388 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 19390, starting the watchdog process... # I am the watchdog process 19391, sleeping for 360 seconds... # The watchdog process 19391 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 = 1376947411.20071, t0 = 1376947409.69916 # dt = 1.50155282020569, rt = 0.00103521347045898 ok 2 ok 3 ok 4 # clock = 0.04 # clock = 0.04 0.11 # clock = 0.04 0.11 0.18 # clock = 0.04 0.11 0.18 0.26 ok 5 # I am the main process 19390, terminating the watchdog process 19391 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 19391 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 19392, starting the watchdog process... # I am the watchdog process 19393, sleeping for 360 seconds... # The watchdog process 19393 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19392, terminating the watchdog process 19393 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19393 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 19394, starting the watchdog process... # I am the watchdog process 19395, sleeping for 360 seconds... # The watchdog process 19395 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.499924 0.400939 # Tick! 2 0.510686 # Tick! 1 0.910872 # Tick! 0 1.319648 # Tick! 0 1.721998 # getitimer: 0 0 ok 2 # I am the main process 19394, terminating the watchdog process 19395 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19395 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 19396, starting the watchdog process... # I am the watchdog process 19397, sleeping for 360 seconds... # The watchdog process 19397 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 19396, terminating the watchdog process 19397 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19397 = 1 # All done. ok t/sleep.t ......... 1..4 # 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; ok 2 - negative time error # time...1376947416.37062 ok 3 # sleep...0.501029 ok 4 # I am the main process 19398, terminating the watchdog process 19399 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19399 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 19400, starting the watchdog process... # I am the watchdog process 19401, sleeping for 360 seconds... # The watchdog process 19401 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 ok 16 ok 17 ok 18 ok 19 ok 20 ok 21 ok 22 ok 23 ok 24 ok 25 ok 26 ok 27 ok 28 ok 29 ok 30 ok 31 ok 32 ok 33 ok 34 ok 35 # mtime = 1376947417 1376947417 1376947417 1376947417 1376947418 # atime = 1376947417 1376947417 1376947417 1376947418 1376947418 # ai = 4, mi = 4, ss = 0 ok 36 # skip no subsecond timestamps detected ok 37 ok 38 ok 39 ok 40 ok 41 ok 42 ok 43 # I am the main process 19400, terminating the watchdog process 19401 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 19401 = 1 # All done. ok t/time.t .......... 1..2 # 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... ok 1 - require Time::HiRes; ok 2 # s = 53.3046138286591, n = 100, s/n = 0.533046138286591 # I am the main process 19402, terminating the watchdog process 19403 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19403 = 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 19405, starting the watchdog process... # I am the watchdog process 19406, sleeping for 360 seconds... # The watchdog process 19406 launched, continuing testing... ok 1 # tick = 1, one = 1376947418, two = 1376947418, three = 1376947418 ok 2 # tick = 3, one = 1376947418, two = 1376947418, three = 1376947418 ok 3 - negative time error # t0 = 1376947418.6734 # ualarm(100000) # t1 = 1376947418.77436 # dt = 0.100960969924927 # r = 1.00960969924927 ok 4 # t0 = 1376947418.77524 # ualarm(1100000) # t1 = 1376947419.87653 # dt = 1.1012921333313 # r = 1.00117466666482 ok 5 # t0 = 1376947419.87786 # ualarm(2200000) # t1 = 1376947422.07888 # dt = 2.20101809501648 # r = 1.00046277046204 ok 6 # t0 = 1376947422.08063 # ualarm(4300000) # t1 = 1376947426.38194 # dt = 4.30130887031555 # r = 1.00030438844548 ok 7 # t0 = 1376947426.38395 # got0 = 0 # t1 = 1376947426.68395 # t1 - t0 = 0.300002098083496 # got1 = 200004 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 19405, terminating the watchdog process 19406 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 19406 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 19442, starting the watchdog process... # I am the watchdog process 19443, sleeping for 360 seconds... # The watchdog process 19443 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19442, terminating the watchdog process 19443 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19443 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.07 usr 0.03 sys + 13.66 cusr 1.15 csys = 14.91 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9726.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.9726-o3ldg7 && tar cvf - Time-HiRes-1.9726.ppd blib) | gzip -c >/home/fly1600/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Time-HiRes-1.9726.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/fly1600/var/cpan/build/Time-HiRes-1.9726-o3ldg7/Time-HiRes-1.9726.ppd /home/fly1600/var/REPO/Z/ZE/ZEFRAM VmSize: 238560 kB Finished 2013-08-19T14:23:52