PATH=/usr/bin:/bin:/home/fly1800/var/megalib/bin Start 2013-08-18T00:01:08 ActivePerl-1800 CPAN-2.00 Reading '/home/fly1800/var/cpan/Metadata' Database was generated on Sun, 18 Aug 2013 07:07:48 GMT Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz ok Time-HiRes-1.9726/ Time-HiRes-1.9726/HiRes.pm Time-HiRes-1.9726/MANIFEST Time-HiRes-1.9726/Makefile.PL Time-HiRes-1.9726/HiRes.xs Time-HiRes-1.9726/META.json Time-HiRes-1.9726/META.yml Time-HiRes-1.9726/README Time-HiRes-1.9726/TODO Time-HiRes-1.9726/Changes Time-HiRes-1.9726/fallback/ Time-HiRes-1.9726/fallback/const-xs.inc Time-HiRes-1.9726/fallback/const-c.inc Time-HiRes-1.9726/t/ Time-HiRes-1.9726/t/usleep.t Time-HiRes-1.9726/t/time.t Time-HiRes-1.9726/t/itimer.t Time-HiRes-1.9726/t/stat.t Time-HiRes-1.9726/t/Watchdog.pm Time-HiRes-1.9726/t/tv_interval.t Time-HiRes-1.9726/t/gettimeofday.t Time-HiRes-1.9726/t/sleep.t Time-HiRes-1.9726/t/clock.t Time-HiRes-1.9726/t/alarm.t Time-HiRes-1.9726/t/nanosleep.t Time-HiRes-1.9726/t/ualarm.t Time-HiRes-1.9726/hints/ Time-HiRes-1.9726/hints/sco.pl Time-HiRes-1.9726/hints/dec_osf.pl Time-HiRes-1.9726/hints/aix.pl Time-HiRes-1.9726/hints/irix.pl Time-HiRes-1.9726/hints/svr4.pl Time-HiRes-1.9726/hints/linux.pl Time-HiRes-1.9726/hints/dynixptx.pl Time-HiRes-1.9726/hints/solaris.pl Time-HiRes-1.9726/ppport.h Time-HiRes-1.9726/typemap CPAN.pm: Building Z/ZE/ZEFRAM/Time-HiRes-1.9726.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/var/megalib/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.9726\" -DXS_VERSION=\"1.9726\" -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.9726.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 25551, starting the watchdog process... # I am the watchdog process 25552, sleeping for 360 seconds... # The watchdog process 25552 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.301383 # Select returned! 4 0.301648 # 0.899450666666667 # Tick! 3 0.603724 # Select returned! 3 0.603828 # 0.798724 # Tick! 2 0.905576 # Select returned! 2 0.905739 # 0.698087 # Tick! 1 1.207391 # Select returned! 1 1.207519 # 0.597493666666667 # Tick! 0 1.510219 # Select returned! 0 1.510395 # 0.496535 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1376809278.73165, t0 = 1376809278.73154, dt = 0.000102043151855469 # N = 2048, t1 = 1376809278.73194, t0 = 1376809278.73171, dt = 0.000235080718994141 # N = 4096, t1 = 1376809278.7324, t0 = 1376809278.732, dt = 0.000400066375732422 # N = 8192, t1 = 1376809278.73332, t0 = 1376809278.73244, dt = 0.000881195068359375 # N = 16384, t1 = 1376809278.73502, t0 = 1376809278.73337, dt = 0.00164294242858887 # N = 32768, t1 = 1376809278.73852, t0 = 1376809278.73502, dt = 0.00350785255432129 # N = 65536, t1 = 1376809278.74692, t0 = 1376809278.73858, dt = 0.00833702087402344 # N = 131072, t1 = 1376809278.7599, t0 = 1376809278.74698, dt = 0.0129249095916748 # Alarm 1 - 1376809278.77094 # Alarm 2 - 1376809278.79775 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 25551, terminating the watchdog process 25552 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 25552 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 25553, starting the watchdog process... # I am the watchdog process 25554, sleeping for 360 seconds... # The watchdog process 25554 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 = 1376809284.51164, t0 = 1376809283.00877 # dt = 1.50286626815796, rt = 0.0019108454386394 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.13 # clock = 0.03 0.13 0.23 # clock = 0.03 0.13 0.23 0.33 ok 5 # I am the main process 25553, terminating the watchdog process 25554 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 25554 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 25555, starting the watchdog process... # I am the watchdog process 25556, sleeping for 360 seconds... # The watchdog process 25556 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 25555, terminating the watchdog process 25556 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25556 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 25557, starting the watchdog process... # I am the watchdog process 25558, sleeping for 360 seconds... # The watchdog process 25558 launched, continuing testing... # setitimer: 0 0 ok 1 # getitimer: 0.499924 0.400939 # Tick! 2 0.505631 # Tick! 1 0.908521 # Tick! 0 1.313113 # Tick! 0 1.716787 # getitimer: 0 0 ok 2 # I am the main process 25557, terminating the watchdog process 25558 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 25558 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 25559, starting the watchdog process... # I am the watchdog process 25560, sleeping for 360 seconds... # The watchdog process 25560 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 25559, terminating the watchdog process 25560 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 25560 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 25561, starting the watchdog process... # I am the watchdog process 25562, sleeping for 360 seconds... # The watchdog process 25562 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1376809289.75111 ok 3 # sleep...0.501601 ok 4 # I am the main process 25561, terminating the watchdog process 25562 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25562 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 25563, starting the watchdog process... # I am the watchdog process 25564, sleeping for 360 seconds... # The watchdog process 25564 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 ok 16 ok 17 ok 18 ok 19 ok 20 ok 21 ok 22 ok 23 ok 24 ok 25 ok 26 ok 27 ok 28 ok 29 ok 30 ok 31 ok 32 ok 33 ok 34 ok 35 # mtime = 1376809290 1376809290 1376809290 1376809291 1376809291 # atime = 1376809290 1376809290 1376809291 1376809291 1376809291 # ai = 4, mi = 4, ss = 0 ok 36 # skip no subsecond timestamps detected ok 37 ok 38 ok 39 ok 40 ok 41 ok 42 ok 43 # I am the main process 25563, terminating the watchdog process 25564 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 25564 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 25565, starting the watchdog process... # I am the watchdog process 25566, sleeping for 360 seconds... # The watchdog process 25566 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 70.8370542526245, n = 100, s/n = 0.708370542526245 # I am the main process 25565, terminating the watchdog process 25566 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 25566 = 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 25568, starting the watchdog process... # I am the watchdog process 25569, sleeping for 360 seconds... # The watchdog process 25569 launched, continuing testing... ok 1 # tick = 1, one = 1376809291, two = 1376809291, three = 1376809291 ok 2 # tick = 3, one = 1376809291, two = 1376809291, three = 1376809291 ok 3 - negative time error # t0 = 1376809291.85543 # ualarm(100000) # t1 = 1376809291.9564 # dt = 0.100966930389404 # r = 1.00966930389404 ok 4 # t0 = 1376809291.95726 # ualarm(1100000) # t1 = 1376809293.05969 # dt = 1.1024329662323 # r = 1.00221178748391 ok 5 # t0 = 1376809293.06116 # ualarm(2200000) # t1 = 1376809295.26373 # dt = 2.20257592201233 # r = 1.00117087364197 ok 6 # t0 = 1376809295.26548 # ualarm(4300000) # t1 = 1376809299.56729 # dt = 4.30181193351746 # r = 1.00042137988778 ok 7 # t0 = 1376809299.56891 # got0 = 0 # t1 = 1376809299.86891 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 25568, terminating the watchdog process 25569 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 25569 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 25570, starting the watchdog process... # I am the watchdog process 25571, sleeping for 360 seconds... # The watchdog process 25571 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 25570, terminating the watchdog process 25571 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 25571 = 1 # All done. ok All tests successful. Files=11, Tests=95, 26 wallclock secs ( 0.07 usr 0.03 sys + 11.53 cusr 3.27 csys = 14.90 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9726.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.9726-Mg_faF && tar cvf - Time-HiRes-1.9726.ppd blib) | gzip -c >/home/fly1800/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9726.tar.gz Time-HiRes-1.9726.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.9726-Mg_faF/Time-HiRes-1.9726.ppd /home/fly1800/var/REPO/Z/ZE/ZEFRAM VmSize: 129568 kB Finished 2013-08-18T00:01:44