PATH=/home/fly1800/bin:/usr/kerberos/bin:/usr/local/bin:/bin:/usr/bin:/home/fly1800/bin Start 2013-06-12T14:55:05 ActivePerl-1800 CPAN-2.00 Reading '/home/fly1800/var/cpan/Metadata' Database was generated on Wed, 12 Jun 2013 20:53:03 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: Building Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz >>> /home/fly1800/ap1800-297235/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/fly1800/ap1800-297235/bin/perl-static /home/fly1800/ap1800-297235/lib/ExtUtils/xsubpp -typemap /home/fly1800/ap1800-297235/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 -fstack-protector -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9725\" -DXS_VERSION=\"1.9725\" -fPIC "-I/home/fly1800/ap1800-297235/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 -fstack-protector 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/fly1800/ap1800-297235/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 20052, starting the watchdog process... # I am the watchdog process 20053, sleeping for 360 seconds... # The watchdog process 20053 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.30154 # Select returned! 4 0.30166 # 0.899446666666667 # Tick! 3 0.602715 # Select returned! 3 0.602787 # 0.799071 # Tick! 2 0.903909 # Select returned! 2 0.903979 # 0.698673666666667 # Tick! 1 1.205137 # Select returned! 1 1.205207 # 0.598264333333333 # Tick! 0 1.50635 # Select returned! 0 1.50644 # 0.497853333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1371074113.58937, t0 = 1371074113.58929, dt = 8.0108642578125e-05 # N = 2048, t1 = 1371074113.58965, t0 = 1371074113.58947, dt = 0.000182151794433594 # N = 4096, t1 = 1371074113.5901, t0 = 1371074113.58973, dt = 0.000369071960449219 # N = 8192, t1 = 1371074113.59088, t0 = 1371074113.59016, dt = 0.000715017318725586 # N = 16384, t1 = 1371074113.59174, t0 = 1371074113.59094, dt = 0.00079798698425293 # N = 32768, t1 = 1371074113.59516, t0 = 1371074113.5918, dt = 0.00336813926696777 # N = 65536, t1 = 1371074113.5996, t0 = 1371074113.59543, dt = 0.00416707992553711 # N = 131072, t1 = 1371074113.60958, t0 = 1371074113.59972, dt = 0.00985908508300781 # N = 262144, t1 = 1371074113.62842, t0 = 1371074113.60981, dt = 0.018610954284668 # Alarm 1 - 1371074113.63956 # Alarm 2 - 1371074113.67507 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 20052, terminating the watchdog process 20053 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 20053 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 20054, starting the watchdog process... # I am the watchdog process 20055, sleeping for 360 seconds... # The watchdog process 20055 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 = 1371074119.43598, t0 = 1371074117.9344 # dt = 1.50158190727234, rt = 0.00105460484822584 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.09 # clock = 0.03 0.09 0.16 # clock = 0.03 0.09 0.16 0.22 ok 5 # I am the main process 20054, terminating the watchdog process 20055 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 20055 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 20056, starting the watchdog process... # I am the watchdog process 20057, sleeping for 360 seconds... # The watchdog process 20057 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 20056, terminating the watchdog process 20057 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20057 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 20058, starting the watchdog process... # I am the watchdog process 20059, sleeping for 360 seconds... # The watchdog process 20059 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.500923 0.400939 # Tick! 2 0.50638 # Tick! 1 0.90977 # Tick! 0 1.317956 # Tick! 0 1.723184 # getitimer: 0 0 ok 2 # I am the main process 20058, terminating the watchdog process 20059 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20059 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 20060, starting the watchdog process... # I am the watchdog process 20061, sleeping for 360 seconds... # The watchdog process 20061 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 20060, terminating the watchdog process 20061 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 20061 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 20062, starting the watchdog process... # I am the watchdog process 20063, sleeping for 360 seconds... # The watchdog process 20063 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1371074124.58945 ok 3 # sleep...0.500813 ok 4 # I am the main process 20062, terminating the watchdog process 20063 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20063 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 20064, starting the watchdog process... # I am the watchdog process 20065, sleeping for 360 seconds... # The watchdog process 20065 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 = 1371074125 1371074125 1371074126 1371074126 1371074126 # atime = 1371074125 1371074125 1371074126 1371074126 1371074126 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 20064, terminating the watchdog process 20065 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20065 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 20066, starting the watchdog process... # I am the watchdog process 20067, sleeping for 360 seconds... # The watchdog process 20067 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 92.5854411125183, n = 100, s/n = 0.925854411125183 # I am the main process 20066, terminating the watchdog process 20067 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 20067 = 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 20069, starting the watchdog process... # I am the watchdog process 20070, sleeping for 360 seconds... # The watchdog process 20070 launched, continuing testing... ok 1 # tick = 1, one = 1371074127, two = 1371074127, three = 1371074127 ok 2 # tick = 3, one = 1371074127, two = 1371074127, three = 1371074127 ok 3 - negative time error # t0 = 1371074127.07536 # ualarm(100000) # t1 = 1371074127.17642 # dt = 0.101056814193726 # r = 1.01056814193726 ok 4 # t0 = 1371074127.17738 # ualarm(1100000) # t1 = 1371074128.27936 # dt = 1.10198307037354 # r = 1.00180279124867 ok 5 # t0 = 1371074128.28122 # ualarm(2200000) # t1 = 1371074130.4826 # dt = 2.20137882232666 # r = 1.00062673742121 ok 6 # t0 = 1371074130.48415 # ualarm(4300000) # t1 = 1371074134.78592 # dt = 4.30176901817322 # r = 1.00041139957517 ok 7 # t0 = 1371074134.78745 # got0 = 0 # t1 = 1371074135.08745 # t1 - t0 = 0.300000905990601 # got1 = 200011 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 20069, terminating the watchdog process 20070 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 20070 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 20071, starting the watchdog process... # I am the watchdog process 20072, sleeping for 360 seconds... # The watchdog process 20072 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 20071, terminating the watchdog process 20072 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 20072 = 1 # All done. ok All tests successful. Files=11, Tests=68, 28 wallclock secs ( 0.06 usr 0.04 sys + 14.32 cusr 0.15 csys = 14.57 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/fly1800/var/cpan/build/Time-HiRes-1.9725-9zU8a9 && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/home/fly1800/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Time-HiRes-1.9725.ppd blib/ 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/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1800/var/cpan/build/Time-HiRes-1.9725-9zU8a9/Time-HiRes-1.9725.ppd /home/fly1800/var/REPO/Z/ZE/ZEFRAM VmSize: 337720 kB Finished 2013-06-12T14:55:40