PATH=/usr/bin:/bin Start 2012-02-01T17:23:19 ActivePerl-1003 CPAN-1.9402 Going to read '/home/cpanfly/var/cpan/Metadata' Database was generated on Thu, 02 Feb 2012 00:11:56 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 Will not use Archive::Tar, need 1.00 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: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz >>> /home/cpanfly/ap1003/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/cpanfly/ap1003/bin/perl-static /home/cpanfly/var/megalib/ExtUtils/xsubpp -typemap /home/cpanfly/ap1003/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 -DPRIVLIB_LAST_IN_INC -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9725\" -DXS_VERSION=\"1.9725\" -fPIC "-I/home/cpanfly/ap1003/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.9725.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/cpanfly/ap1003/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 20535, starting the watchdog process... # I am the watchdog process 20536, sleeping for 360 seconds... # The watchdog process 20536 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.299387 # Select returned! 4 0.299682 # 0.900106 # Tick! 3 0.599417 # Select returned! 3 0.599599 # 0.800133666666667 # Tick! 2 0.921482 # Select returned! 2 0.921696 # 0.692768 # Tick! 1 1.219563 # Select returned! 1 1.219842 # 0.593386 # Tick! 0 1.527305 # Select returned! 0 1.52751 # 0.49083 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1328145806.99821, t0 = 1328145806.99811, dt = 0.000101089477539062 # N = 2048, t1 = 1328145806.99855, t0 = 1328145806.9984, dt = 0.000146865844726562 # N = 4096, t1 = 1328145806.99897, t0 = 1328145806.99871, dt = 0.000258922576904297 # N = 8192, t1 = 1328145806.99981, t0 = 1328145806.99913, dt = 0.000680923461914062 # N = 16384, t1 = 1328145807.00155, t0 = 1328145806.99997, dt = 0.00157904624938965 # N = 32768, t1 = 1328145807.0047, t0 = 1328145807.00171, dt = 0.00298595428466797 # N = 65536, t1 = 1328145807.01111, t0 = 1328145807.00485, dt = 0.00625705718994141 # N = 131072, t1 = 1328145807.02341, t0 = 1328145807.01129, dt = 0.0121238231658936 # Alarm 1 - 1328145807.02876 # Alarm 2 - 1328145807.05252 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 20535, terminating the watchdog process 20536 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 20536 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 20537, starting the watchdog process... # I am the watchdog process 20538, sleeping for 360 seconds... # The watchdog process 20538 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 = 1328145812.74992, t0 = 1328145811.23056 # dt = 1.51936602592468, rt = 0.0129106839497883 ok 2 ok 3 ok 4 # skip no clock_nanosleep # clock = 0.03 # clock = 0.03 0.16 # clock = 0.03 0.16 0.27 # clock = 0.03 0.16 0.27 0.39 ok 5 # I am the main process 20537, terminating the watchdog process 20538 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 20538 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 20539, starting the watchdog process... # I am the watchdog process 20540, sleeping for 360 seconds... # The watchdog process 20540 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 20539, terminating the watchdog process 20540 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20540 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 20541, starting the watchdog process... # I am the watchdog process 20542, sleeping for 360 seconds... # The watchdog process 20542 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.51 0.4 # Tick! 2 0.536921 # Tick! 1 1.09698 # Tick! 0 1.497075 # Tick! 0 2.047172 # getitimer: 0 0 ok 2 # I am the main process 20541, terminating the watchdog process 20542 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 20542 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 20543, starting the watchdog process... # I am the watchdog process 20544, sleeping for 360 seconds... # The watchdog process 20544 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 20543, terminating the watchdog process 20544 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 20544 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 20545, starting the watchdog process... # I am the watchdog process 20546, sleeping for 360 seconds... # The watchdog process 20546 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1328145817.0449 ok 3 # sleep...0.505496 ok 4 # I am the main process 20545, terminating the watchdog process 20546 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 20546 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 20547, starting the watchdog process... # I am the watchdog process 20548, sleeping for 360 seconds... # The watchdog process 20548 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 = 1328145817 1328145818 1328145818 1328145819 1328145819 # atime = 1328145818 1328145818 1328145818 1328145819 1328145819 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 20547, terminating the watchdog process 20548 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 20548 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 20549, starting the watchdog process... # I am the watchdog process 20550, sleeping for 360 seconds... # The watchdog process 20550 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 65.8194954395294, n = 100, s/n = 0.658194954395294 # I am the main process 20549, terminating the watchdog process 20550 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 20550 = 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 20552, starting the watchdog process... # I am the watchdog process 20553, sleeping for 360 seconds... # The watchdog process 20553 launched, continuing testing... ok 1 # tick = 1, one = 1328145819, two = 1328145819, three = 1328145819 ok 2 # tick = 3, one = 1328145819, two = 1328145819, three = 1328145819 ok 3 - negative time error # t0 = 1328145819.76329 # ualarm(100000) # t1 = 1328145819.86217 # dt = 0.0988810062408447 # r = 0.988810062408447 ok 4 # t0 = 1328145819.86291 # ualarm(1100000) # t1 = 1328145821.04155 # dt = 1.17864298820496 # r = 1.07149362564087 ok 5 # t0 = 1328145821.04257 # ualarm(2200000) # t1 = 1328145823.24184 # dt = 2.19926404953003 # r = 0.999665477059104 ok 6 # t0 = 1328145823.2427 # ualarm(4300000) # t1 = 1328145827.54361 # dt = 4.30090999603271 # r = 1.00021162698435 ok 7 # t0 = 1328145827.54456 # got0 = 0 # t1 = 1328145827.84456 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 20552, terminating the watchdog process 20553 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 20553 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 20554, starting the watchdog process... # I am the watchdog process 20555, sleeping for 360 seconds... # The watchdog process 20555 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 20554, terminating the watchdog process 20555 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 20555 = 1 # All done. ok All tests successful. Files=11, Tests=68, 27 wallclock secs ( 0.02 usr 0.00 sys + 10.96 cusr 1.64 csys = 12.62 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/cpanfly/var/cpan/build/Time-HiRes-1.9725-ZZEaMZ && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/home/cpanfly/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Time-HiRes-1.9725.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/cpanfly/var/cpan/build/Time-HiRes-1.9725-ZZEaMZ/Time-HiRes-1.9725.ppd /home/cpanfly/var/REPO/Z/ZE/ZEFRAM Finished 2012-02-01T17:23:52