PATH=/usr/bin:/bin Start 2012-02-01T21:11:24 ActivePerl-1200 CPAN-1.9402 Going to read '/home/fly1200/var/cpan/Metadata' Database was generated on Thu, 02 Feb 2012 04:11:10 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-292396/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/fly1200/ap1200-292396/bin/perl-static /home/fly1200/var/megalib/ExtUtils/xsubpp -typemap /home/fly1200/ap1200-292396/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -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-292396/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.9725.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /home/fly1200/ap1200-292396/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 11238, starting the watchdog process... # I am the watchdog process 11239, sleeping for 360 seconds... # The watchdog process 11239 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.36454 # Select returned! 4 0.36466 # 0.878446666666667 # Tick! 3 0.665492 # Select returned! 3 0.665614 # 0.778128666666667 # Tick! 2 0.96838 # Select returned! 2 0.968496 # 0.677168 # Tick! 1 1.270408 # Select returned! 1 1.270704 # 0.576432 # Tick! 0 1.572362 # Select returned! 0 1.572711 # 0.475763 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1328159492.67587, t0 = 1328159492.67571, dt = 0.000154972076416016 # N = 2048, t1 = 1328159492.67613, t0 = 1328159492.67599, dt = 0.000138998031616211 # N = 4096, t1 = 1328159492.67643, t0 = 1328159492.67617, dt = 0.000252962112426758 # N = 8192, t1 = 1328159492.67693, t0 = 1328159492.67647, dt = 0.00046086311340332 # N = 16384, t1 = 1328159492.67724, t0 = 1328159492.67693, dt = 0.000313997268676758 # N = 32768, t1 = 1328159492.67848, t0 = 1328159492.6774, dt = 0.00108098983764648 # N = 65536, t1 = 1328159492.68335, t0 = 1328159492.67921, dt = 0.00413703918457031 # N = 131072, t1 = 1328159492.69089, t0 = 1328159492.68418, dt = 0.00670504570007324 # N = 262144, t1 = 1328159492.70688, t0 = 1328159492.69288, dt = 0.0140068531036377 # Alarm 1 - 1328159492.71988 # Alarm 2 - 1328159492.75287 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 11238, terminating the watchdog process 11239 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 11239 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 11241, starting the watchdog process... # I am the watchdog process 11242, sleeping for 360 seconds... # The watchdog process 11242 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 = 1328159498.47237, t0 = 1328159496.9674 # dt = 1.50497508049011, rt = 0.00331672032674146 ok 2 ok 3 ok 4 # clock = 0.02 # clock = 0.02 0.08 # clock = 0.02 0.08 0.14 # clock = 0.02 0.08 0.14 0.2 ok 5 # I am the main process 11241, terminating the watchdog process 11242 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 11242 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 11243, starting the watchdog process... # The watchdog process 11244 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 11244, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 11243, terminating the watchdog process 11244 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 11244 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 11245, starting the watchdog process... # I am the watchdog process 11246, sleeping for 360 seconds... # The watchdog process 11246 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.501923 0.400939 # Tick! 2 0.543597 # Tick! 1 0.967628 # Tick! 0 1.381527 # Tick! 0 1.786817 # getitimer: 0 0 ok 2 # I am the main process 11245, terminating the watchdog process 11246 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 11246 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 11247, starting the watchdog process... # I am the watchdog process 11248, sleeping for 360 seconds... # The watchdog process 11248 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 11247, terminating the watchdog process 11248 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 11248 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 11249, starting the watchdog process... # I am the watchdog process 11250, sleeping for 360 seconds... # The watchdog process 11250 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1328159503.60705 ok 3 # sleep...0.500866 ok 4 # I am the main process 11249, terminating the watchdog process 11250 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 11250 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 11251, starting the watchdog process... # I am the watchdog process 11252, sleeping for 360 seconds... # The watchdog process 11252 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 = 1328159504 1328159504 1328159504 1328159505 1328159505 # atime = 1328159504 1328159504 1328159504 1328159505 1328159505 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 11251, terminating the watchdog process 11252 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 11252 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 11253, starting the watchdog process... # I am the watchdog process 11254, sleeping for 360 seconds... # The watchdog process 11254 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 58.2311463356018, n = 100, s/n = 0.582311463356018 # I am the main process 11253, terminating the watchdog process 11254 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 11254 = 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 11256, starting the watchdog process... # I am the watchdog process 11257, sleeping for 360 seconds... # The watchdog process 11257 launched, continuing testing... ok 1 # tick = 1, one = 1328159505, two = 1328159505, three = 1328159505 ok 2 # tick = 3, one = 1328159505, two = 1328159505, three = 1328159505 ok 3 - negative time error # t0 = 1328159505.70041 # ualarm(100000) # t1 = 1328159505.80414 # dt = 0.103730916976929 # r = 1.03730916976929 ok 4 # t0 = 1328159505.80641 # ualarm(1100000) # t1 = 1328159506.90807 # dt = 1.10165596008301 # r = 1.00150541825728 ok 5 # t0 = 1328159506.91047 # ualarm(2200000) # t1 = 1328159509.11343 # dt = 2.20296001434326 # r = 1.00134546106512 ok 6 # t0 = 1328159509.11619 # ualarm(4300000) # t1 = 1328159513.41979 # dt = 4.30359506607056 # r = 1.00083606187687 ok 7 # t0 = 1328159513.42253 # got0 = 0 # t1 = 1328159513.72253 # t1 - t0 = 0.300000905990601 # got1 = 200003 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 11256, terminating the watchdog process 11257 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 11257 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 11259, starting the watchdog process... # I am the watchdog process 11260, sleeping for 360 seconds... # The watchdog process 11260 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 11259, terminating the watchdog process 11260 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 11260 = 1 # All done. ok All tests successful. Files=11, Tests=68, 26 wallclock secs ( 0.06 usr 0.04 sys + 14.07 cusr 0.37 csys = 14.54 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-6Kk7aO && 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-6Kk7aO/Time-HiRes-1.9725.ppd /home/fly1200/var/REPO/Z/ZE/ZEFRAM Finished 2012-02-01T21:11:58