PATH=/usr/bin:/bin Start 2012-02-01T15:17:30 ActivePerl-1200 CPAN-1.9402 Going to read '/home/fly1200/var/cpan/Metadata' Database was generated on Wed, 01 Feb 2012 22:25:08 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: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9725.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.9725\" -DXS_VERSION=\"1.9725\" -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.9725.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 30103, starting the watchdog process... # I am the watchdog process 30104, sleeping for 360 seconds... # The watchdog process 30104 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.338797 # Select returned! 4 0.338981 # 0.887006333333333 # Tick! 3 0.633169 # Select returned! 3 0.633449 # 0.788850333333333 # Tick! 2 0.933222 # Select returned! 2 0.933496 # 0.688834666666667 # Tick! 1 1.338343 # Select returned! 1 1.338519 # 0.553827 # Tick! 0 1.633343 # Select returned! 0 1.633594 # 0.455468666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1328138257.31004, t0 = 1328138257.30994, dt = 0.000102996826171875 # N = 2048, t1 = 1328138257.31039, t0 = 1328138257.3102, dt = 0.000192880630493164 # N = 4096, t1 = 1328138257.3112, t0 = 1328138257.31059, dt = 0.000607967376708984 # N = 8192, t1 = 1328138257.3126, t0 = 1328138257.31141, dt = 0.00118494033813477 # N = 16384, t1 = 1328138257.31518, t0 = 1328138257.31283, dt = 0.00234889984130859 # N = 32768, t1 = 1328138257.31958, t0 = 1328138257.31536, dt = 0.0042111873626709 # N = 65536, t1 = 1328138257.32628, t0 = 1328138257.3198, dt = 0.00647497177124023 # N = 131072, t1 = 1328138257.33907, t0 = 1328138257.32642, dt = 0.0126469135284424 # Alarm 1 - 1328138257.34824 # Alarm 2 - 1328138257.37255 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 30103, terminating the watchdog process 30104 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 30104 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 30105, starting the watchdog process... # I am the watchdog process 30106, sleeping for 360 seconds... # The watchdog process 30106 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 = 1328138263.05944, t0 = 1328138261.54531 # dt = 1.51412987709045, rt = 0.00941991806030273 ok 2 ok 3 ok 4 # skip no clock_nanosleep # clock = 0.03 # clock = 0.03 0.09 # clock = 0.03 0.09 0.16 # clock = 0.03 0.09 0.16 0.23 ok 5 # I am the main process 30105, terminating the watchdog process 30106 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 30106 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 30107, starting the watchdog process... # I am the watchdog process 30108, sleeping for 360 seconds... # The watchdog process 30108 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 30107, terminating the watchdog process 30108 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 30108 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 30109, starting the watchdog process... # I am the watchdog process 30110, sleeping for 360 seconds... # The watchdog process 30110 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.51 0.4 # Tick! 2 0.637936 # Tick! 1 1.067088 # Tick! 0 1.498089 # Tick! 0 2.028099 # getitimer: 0 0 ok 2 # I am the main process 30109, terminating the watchdog process 30110 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 30110 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 30111, starting the watchdog process... # I am the watchdog process 30112, sleeping for 360 seconds... # The watchdog process 30112 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 30111, terminating the watchdog process 30112 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 30112 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 30113, starting the watchdog process... # I am the watchdog process 30114, sleeping for 360 seconds... # The watchdog process 30114 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1328138267.05378 ok 3 # sleep...0.506196 ok 4 # I am the main process 30113, terminating the watchdog process 30114 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 30114 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 30115, starting the watchdog process... # I am the watchdog process 30116, sleeping for 360 seconds... # The watchdog process 30116 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 = 1328138267 1328138268 1328138268 1328138268 1328138269 # atime = 1328138267 1328138268 1328138268 1328138268 1328138269 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 30115, terminating the watchdog process 30116 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 30116 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 30117, starting the watchdog process... # I am the watchdog process 30118, sleeping for 360 seconds... # The watchdog process 30118 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 17.3215863704681, n = 100, s/n = 0.173215863704681 # I am the main process 30117, terminating the watchdog process 30118 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 30118 = 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 30120, starting the watchdog process... # I am the watchdog process 30121, sleeping for 360 seconds... # The watchdog process 30121 launched, continuing testing... ok 1 # tick = 1, one = 1328138269, two = 1328138269, three = 1328138269 ok 2 # tick = 3, one = 1328138269, two = 1328138269, three = 1328138269 ok 3 - negative time error # t0 = 1328138269.32033 # ualarm(100000) # t1 = 1328138269.41062 # dt = 0.0902891159057617 # r = 0.902891159057617 ok 4 # t0 = 1328138269.41128 # ualarm(1100000) # t1 = 1328138270.51084 # dt = 1.09955596923828 # r = 0.999596335671165 ok 5 # t0 = 1328138270.51161 # ualarm(2200000) # t1 = 1328138272.7113 # dt = 2.19969892501831 # r = 0.999863147735596 ok 6 # t0 = 1328138272.71212 # ualarm(4300000) # t1 = 1328138277.01215 # dt = 4.30002093315125 # r = 1.00000486817471 ok 7 # t0 = 1328138277.01304 # got0 = 0 # t1 = 1328138277.33033 # t1 - t0 = 0.317286968231201 # got1 = 190000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 30120, terminating the watchdog process 30121 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 30121 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 30122, starting the watchdog process... # I am the watchdog process 30123, sleeping for 360 seconds... # The watchdog process 30123 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 30122, terminating the watchdog process 30123 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 30123 = 1 # All done. ok All tests successful. Files=11, Tests=68, 26 wallclock secs ( 0.02 usr 0.01 sys + 10.94 cusr 1.68 csys = 12.65 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/fly1200/var/cpan/build/Time-HiRes-1.9725-z_OVR1 && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/home/fly1200/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/fly1200/var/cpan/build/Time-HiRes-1.9725-z_OVR1/Time-HiRes-1.9725.ppd /home/fly1200/var/REPO/Z/ZE/ZEFRAM Finished 2012-02-01T15:18:02