PATH=/home/fly1800/bin:/usr/kerberos/bin:/usr/local/bin:/bin:/usr/bin:/home/fly1800/bin Start 2013-06-12T13:52:14 ActivePerl-1800 CPAN-2.00 Reading '/home/fly1800/var/cpan/Metadata' Database was generated on Wed, 12 Jun 2013 20:08:27 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="/lib" 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 19772, starting the watchdog process... # I am the watchdog process 19773, sleeping for 360 seconds... # The watchdog process 19773 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.301894 # Select returned! 4 0.301987 # 0.899337666666667 # Tick! 3 0.602727 # Select returned! 3 0.602812 # 0.799062666666667 # Tick! 2 0.904556 # Select returned! 2 0.904627 # 0.698457666666667 # Tick! 1 1.20598 # Select returned! 1 1.206082 # 0.597972666666667 # Tick! 0 1.508189 # Select returned! 0 1.508265 # 0.497245 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1371070343.4903, t0 = 1371070343.49004, dt = 0.000257968902587891 # N = 2048, t1 = 1371070343.49071, t0 = 1371070343.4904, dt = 0.00030207633972168 # N = 4096, t1 = 1371070343.49108, t0 = 1371070343.4908, dt = 0.00027918815612793 # N = 8192, t1 = 1371070343.49208, t0 = 1371070343.49126, dt = 0.000820159912109375 # N = 16384, t1 = 1371070343.49381, t0 = 1371070343.49225, dt = 0.0015559196472168 # N = 32768, t1 = 1371070343.49808, t0 = 1371070343.49394, dt = 0.00413393974304199 # N = 65536, t1 = 1371070343.50457, t0 = 1371070343.4983, dt = 0.00626802444458008 # N = 131072, t1 = 1371070343.51839, t0 = 1371070343.50483, dt = 0.013559103012085 # Alarm 1 - 1371070343.53136 # Alarm 2 - 1371070343.55896 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 19772, terminating the watchdog process 19773 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 19773 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 19774, starting the watchdog process... # I am the watchdog process 19775, sleeping for 360 seconds... # The watchdog process 19775 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 = 1371070349.26979, t0 = 1371070347.76819 # dt = 1.5016074180603, rt = 0.0010716120402019 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.13 # clock = 0.03 0.13 0.22 # clock = 0.03 0.13 0.22 0.32 ok 5 # I am the main process 19774, terminating the watchdog process 19775 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19775 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 19776, starting the watchdog process... # I am the watchdog process 19777, sleeping for 360 seconds... # The watchdog process 19777 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19776, terminating the watchdog process 19777 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19777 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 19778, starting the watchdog process... # I am the watchdog process 19779, sleeping for 360 seconds... # The watchdog process 19779 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.498924 0.400939 # Tick! 2 0.505415 # Tick! 1 0.907209 # Tick! 0 1.314204 # Tick! 0 1.715781 # getitimer: 0 0 ok 2 # I am the main process 19778, terminating the watchdog process 19779 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19779 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 19780, starting the watchdog process... # I am the watchdog process 19781, sleeping for 360 seconds... # The watchdog process 19781 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 19780, terminating the watchdog process 19781 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19781 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 19782, starting the watchdog process... # I am the watchdog process 19783, sleeping for 360 seconds... # The watchdog process 19783 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1371070354.51324 ok 3 # sleep...0.501348 ok 4 # I am the main process 19782, terminating the watchdog process 19783 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19783 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 19784, starting the watchdog process... # I am the watchdog process 19785, sleeping for 360 seconds... # The watchdog process 19785 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 = 1371070355 1371070355 1371070355 1371070356 1371070356 # atime = 1371070355 1371070355 1371070356 1371070356 1371070356 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 19784, terminating the watchdog process 19785 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19785 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 19786, starting the watchdog process... # I am the watchdog process 19787, sleeping for 360 seconds... # The watchdog process 19787 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 59.8408982753754, n = 100, s/n = 0.598408982753754 # I am the main process 19786, terminating the watchdog process 19787 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19787 = 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 19789, starting the watchdog process... # I am the watchdog process 19790, sleeping for 360 seconds... # The watchdog process 19790 launched, continuing testing... ok 1 # tick = 1, one = 1371070356, two = 1371070356, three = 1371070356 ok 2 # tick = 3, one = 1371070356, two = 1371070356, three = 1371070356 ok 3 - negative time error # t0 = 1371070356.7517 # ualarm(100000) # t1 = 1371070356.85399 # dt = 0.102289915084839 # r = 1.02289915084839 ok 4 # t0 = 1371070356.85546 # ualarm(1100000) # t1 = 1371070357.95732 # dt = 1.10185694694519 # r = 1.00168813358654 ok 5 # t0 = 1371070357.95847 # ualarm(2200000) # t1 = 1371070360.16076 # dt = 2.20229387283325 # r = 1.00104266946966 ok 6 # t0 = 1371070360.16173 # ualarm(4300000) # t1 = 1371070364.46347 # dt = 4.30173683166504 # r = 1.00040391434071 ok 7 # t0 = 1371070364.46498 # got0 = 0 # t1 = 1371070364.76498 # t1 - t0 = 0.300000905990601 # got1 = 199998 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 19789, terminating the watchdog process 19790 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 19790 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 19792, starting the watchdog process... # I am the watchdog process 19793, sleeping for 360 seconds... # The watchdog process 19793 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19792, terminating the watchdog process 19793 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19793 = 1 # All done. ok All tests successful. Files=11, Tests=68, 27 wallclock secs ( 0.07 usr 0.03 sys + 12.09 cusr 2.71 csys = 14.90 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-AMzrMT && 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/man3/ blib/man3/Time::HiRes.3 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 >>> mv /home/fly1800/var/cpan/build/Time-HiRes-1.9725-AMzrMT/Time-HiRes-1.9725.ppd /home/fly1800/var/REPO/Z/ZE/ZEFRAM VmSize: 133600 kB Finished 2013-06-12T13:52:50