PATH=/usr/bin:/bin:/home/fly1400/var/megalib/bin Start 2013-08-19T12:17:29 ActivePerl-1400 CPAN-2.00 Reading '/home/fly1400/var/cpan/Metadata' Database was generated on Mon, 19 Aug 2013 17:29:02 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Fetching with LWP: http://cpan.nas.activestate.com/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Fetching with LWP: http://cpan.nas.activestate.com/authors/id/Z/ZE/ZEFRAM/CHECKSUMS Checksum for /home/fly1400/var/cpan/sources/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/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 and MYMETA.json 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.9726\" -DXS_VERSION=\"1.9726\" -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.9726.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 10437, starting the watchdog process... # I am the watchdog process 10438, sleeping for 360 seconds... # The watchdog process 10438 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.301803 # Select returned! 4 0.301913 # 0.899362333333333 # Tick! 3 0.603824 # Select returned! 3 0.603952 # 0.798682666666667 # Tick! 2 0.904938 # Select returned! 2 0.905016 # 0.698328 # Tick! 1 1.205884 # Select returned! 1 1.205975 # 0.598008333333333 # Tick! 0 1.506972 # Select returned! 0 1.507152 # 0.497616 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1376939858.91507, t0 = 1376939858.91495, dt = 0.000115871429443359 # N = 2048, t1 = 1376939858.91539, t0 = 1376939858.91515, dt = 0.000239133834838867 # N = 4096, t1 = 1376939858.91598, t0 = 1376939858.91546, dt = 0.000514984130859375 # N = 8192, t1 = 1376939858.91694, t0 = 1376939858.91605, dt = 0.000882148742675781 # N = 16384, t1 = 1376939858.91853, t0 = 1376939858.917, dt = 0.00153398513793945 # N = 32768, t1 = 1376939858.92372, t0 = 1376939858.9187, dt = 0.00501203536987305 # N = 65536, t1 = 1376939858.9305, t0 = 1376939858.92382, dt = 0.00667285919189453 # N = 131072, t1 = 1376939858.94446, t0 = 1376939858.93056, dt = 0.0138959884643555 # Alarm 1 - 1376939858.95474 # Alarm 2 - 1376939858.9839 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 10437, terminating the watchdog process 10438 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 10438 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 10439, starting the watchdog process... # I am the watchdog process 10440, sleeping for 360 seconds... # The watchdog process 10440 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 = 1376939864.69634, t0 = 1376939863.19475 # dt = 1.50158905982971, rt = 0.00105937321980787 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.13 # clock = 0.03 0.13 0.21 # clock = 0.03 0.13 0.21 0.31 ok 5 # I am the main process 10439, terminating the watchdog process 10440 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 10440 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 10442, starting the watchdog process... # I am the watchdog process 10443, sleeping for 360 seconds... # The watchdog process 10443 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 10442, terminating the watchdog process 10443 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 10443 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 10444, starting the watchdog process... # I am the watchdog process 10445, sleeping for 360 seconds... # The watchdog process 10445 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.508794 # Tick! 1 0.933085 # Tick! 0 1.356652 # Tick! 0 1.779154 # getitimer: 0 0 ok 2 # I am the main process 10444, terminating the watchdog process 10445 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 10445 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 10446, starting the watchdog process... # I am the watchdog process 10447, sleeping for 360 seconds... # The watchdog process 10447 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 10446, terminating the watchdog process 10447 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 10447 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 10448, starting the watchdog process... # I am the watchdog process 10449, sleeping for 360 seconds... # The watchdog process 10449 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1376939869.96426 ok 3 # sleep...0.501133 ok 4 # I am the main process 10448, terminating the watchdog process 10449 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 10449 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 10450, starting the watchdog process... # I am the watchdog process 10451, sleeping for 360 seconds... # The watchdog process 10451 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 = 1376939870 1376939871 1376939871 1376939871 1376939871 # atime = 1376939870 1376939871 1376939871 1376939871 1376939872 # 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 10450, terminating the watchdog process 10451 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 10451 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 10452, starting the watchdog process... # I am the watchdog process 10453, sleeping for 360 seconds... # The watchdog process 10453 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 14.8209757804871, n = 100, s/n = 0.148209757804871 # I am the main process 10452, terminating the watchdog process 10453 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 10453 = 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 10455, starting the watchdog process... # I am the watchdog process 10456, sleeping for 360 seconds... # The watchdog process 10456 launched, continuing testing... ok 1 # tick = 1, one = 1376939872, two = 1376939872, three = 1376939872 ok 2 # tick = 3, one = 1376939872, two = 1376939872, three = 1376939872 ok 3 - negative time error # t0 = 1376939872.29373 # ualarm(100000) # t1 = 1376939872.39487 # dt = 0.101138114929199 # r = 1.01138114929199 ok 4 # t0 = 1376939872.39577 # ualarm(1100000) # t1 = 1376939873.497 # dt = 1.10123515129089 # r = 1.0011228648099 ok 5 # t0 = 1376939873.49782 # ualarm(2200000) # t1 = 1376939875.70007 # dt = 2.20225501060486 # r = 1.00102500482039 ok 6 # t0 = 1376939875.70148 # ualarm(4300000) # t1 = 1376939880.00246 # dt = 4.30097985267639 # r = 1.00022787271544 ok 7 # t0 = 1376939880.00365 # got0 = 0 # t1 = 1376939880.30365 # t1 - t0 = 0.300004959106445 # got1 = 200003 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 10455, terminating the watchdog process 10456 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 10456 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 10458, starting the watchdog process... # I am the watchdog process 10459, sleeping for 360 seconds... # The watchdog process 10459 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 10458, terminating the watchdog process 10459 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 10459 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.06 usr 0.03 sys + 13.90 cusr 1.22 csys = 15.21 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/fly1400/var/cpan/build/Time-HiRes-1.9726-RVBOvt && tar cvf - Time-HiRes-1.9726.ppd blib) | gzip -c >/home/fly1400/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/fly1400/var/cpan/build/Time-HiRes-1.9726-RVBOvt/Time-HiRes-1.9726.ppd /home/fly1400/var/REPO/Z/ZE/ZEFRAM VmSize: 237944 kB Finished 2013-08-19T12:18:04