PATH=/usr/bin:/bin:/home/fly1200/var/megalib/bin Start 2013-08-18T08:16:18 ActivePerl-1200 CPAN-2.00 Reading '/home/fly1200/var/cpan/Metadata' Database was generated on Sun, 18 Aug 2013 14:07:55 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/fly1200/ap1200/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/fly1200/ap1200/bin/perl-static /home/fly1200/var/megalib/ExtUtils/xsubpp -typemap /home/fly1200/ap1200/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 -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/fly1200/ap1200/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.9726.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly1200/ap1200/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 4653, starting the watchdog process... # I am the watchdog process 4654, sleeping for 360 seconds... # The watchdog process 4654 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.29794 # Select returned! 4 0.298414 # 0.900528666666667 # Tick! 3 0.596961 # Select returned! 3 0.597376 # 0.800874666666667 # Tick! 2 0.896957 # Select returned! 2 0.897368 # 0.700877333333333 # Tick! 1 1.196972 # Select returned! 1 1.197386 # 0.600871333333333 # Tick! 0 1.496958 # Select returned! 0 1.497301 # 0.500899666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1376839000.40991, t0 = 1376839000.40982, dt = 9.39369201660156e-05 # N = 2048, t1 = 1376839000.41031, t0 = 1376839000.41014, dt = 0.000169038772583008 # N = 4096, t1 = 1376839000.41086, t0 = 1376839000.41049, dt = 0.000375986099243164 # N = 8192, t1 = 1376839000.41173, t0 = 1376839000.41105, dt = 0.000686883926391602 # N = 16384, t1 = 1376839000.41332, t0 = 1376839000.41191, dt = 0.00140380859375 # N = 32768, t1 = 1376839000.41642, t0 = 1376839000.41352, dt = 0.00289702415466309 # N = 65536, t1 = 1376839000.48193, t0 = 1376839000.41666, dt = 0.0652649402618408 # Alarm 1 - 1376839000.48771 # Alarm 2 - 1376839000.49923 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 4653, terminating the watchdog process 4654 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 4654 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 4655, starting the watchdog process... # I am the watchdog process 4656, sleeping for 360 seconds... # The watchdog process 4656 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 = 1376839006.4687, t0 = 1376839004.95155 # dt = 1.51715493202209, rt = 0.0114366213480632 ok 2 ok 3 ok 4 # skip no clock_nanosleep # clock = 0.04 # clock = 0.04 0.12 # clock = 0.04 0.12 0.22 # clock = 0.04 0.12 0.22 0.3 ok 5 # I am the main process 4655, terminating the watchdog process 4656 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 4656 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 4657, starting the watchdog process... # I am the watchdog process 4658, sleeping for 360 seconds... # The watchdog process 4658 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 4657, terminating the watchdog process 4658 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 4658 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 4659, starting the watchdog process... # I am the watchdog process 4660, sleeping for 360 seconds... # The watchdog process 4660 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.51 0.4 # Tick! 2 1.379838 # Tick! 1 2.379791 # Tick! 0 3.603765 # Tick! 0 4.669829 # getitimer: 0 0 ok 2 # I am the main process 4659, terminating the watchdog process 4660 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 4660 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 4661, starting the watchdog process... # I am the watchdog process 4662, sleeping for 360 seconds... # The watchdog process 4662 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 4661, terminating the watchdog process 4662 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 4662 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 4663, starting the watchdog process... # I am the watchdog process 4664, sleeping for 360 seconds... # The watchdog process 4664 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1376839013.79776 ok 3 # sleep...0.509401 ok 4 # I am the main process 4663, terminating the watchdog process 4664 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 4664 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 4665, starting the watchdog process... # I am the watchdog process 4666, sleeping for 360 seconds... # The watchdog process 4666 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 = 1376839014 1376839014 1376839015 1376839015 1376839015 # atime = 1376839014 1376839015 1376839015 1376839015 1376839016 # 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 4665, terminating the watchdog process 4666 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 4666 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 4667, starting the watchdog process... # I am the watchdog process 4668, sleeping for 360 seconds... # The watchdog process 4668 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 13.7795016765594, n = 100, s/n = 0.137795016765594 # I am the main process 4667, terminating the watchdog process 4668 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 4668 = 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 4670, starting the watchdog process... # I am the watchdog process 4671, sleeping for 360 seconds... # The watchdog process 4671 launched, continuing testing... ok 1 # tick = 1, one = 1376839016, two = 1376839016, three = 1376839016 ok 2 # tick = 3, one = 1376839016, two = 1376839016, three = 1376839016 ok 3 - negative time error # t0 = 1376839016.40912 # ualarm(100000) # t1 = 1376839016.55799 # dt = 0.148869037628174 # r = 1.48869037628174 ok 4 # t0 = 1376839016.55912 # ualarm(1100000) # t1 = 1376839017.81805 # dt = 1.25893402099609 # r = 1.14448547363281 ok 5 # t0 = 1376839017.8195 # ualarm(2200000) # t1 = 1376839020.04805 # dt = 2.22854495048523 # r = 1.01297497749329 ok 6 # t0 = 1376839020.04941 # ualarm(4300000) # t1 = 1376839024.34806 # dt = 4.29864597320557 # r = 0.999685110047806 ok 7 # t0 = 1376839024.34941 # got0 = 0 # t1 = 1376839024.78811 # t1 - t0 = 0.438699007034302 # got1 = 60000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 4670, terminating the watchdog process 4671 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 4671 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 4672, starting the watchdog process... # I am the watchdog process 4673, sleeping for 360 seconds... # The watchdog process 4673 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 4672, terminating the watchdog process 4673 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 4673 = 1 # All done. ok All tests successful. Files=11, Tests=95, 31 wallclock secs ( 0.08 usr 0.01 sys + 6.44 cusr 0.64 csys = 7.17 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/fly1200/var/cpan/build/Time-HiRes-1.9726-draWeh && tar cvf - Time-HiRes-1.9726.ppd blib) | gzip -c >/home/fly1200/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/fly1200/var/cpan/build/Time-HiRes-1.9726-draWeh/Time-HiRes-1.9726.ppd /home/fly1200/var/REPO/Z/ZE/ZEFRAM VmSize: 119808 kB Finished 2013-08-18T08:17:09