PATH=/usr/bin:/bin:/home/fly1800/cpanfly-5.18/var/megalib/bin Start 2016-03-14T04:06:12 ActivePerl-1800 CPAN-2.10 Reading '/home/fly1800/cpanfly-5.18/var/cpan/Metadata' Database was generated on Mon, 14 Mar 2016 05:53:41 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9732.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /home/fly1800/cpanfly-5.18/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9732.tar.gz ok Time-HiRes-1.9732/ Time-HiRes-1.9732/Changes Time-HiRes-1.9732/fallback/ Time-HiRes-1.9732/hints/ Time-HiRes-1.9732/HiRes.pm Time-HiRes-1.9732/HiRes.xs Time-HiRes-1.9732/Makefile.PL Time-HiRes-1.9732/MANIFEST Time-HiRes-1.9732/META.json Time-HiRes-1.9732/META.yml Time-HiRes-1.9732/ppport.h Time-HiRes-1.9732/README Time-HiRes-1.9732/t/ Time-HiRes-1.9732/TODO Time-HiRes-1.9732/typemap Time-HiRes-1.9732/t/alarm.t Time-HiRes-1.9732/t/clock.t Time-HiRes-1.9732/t/gettimeofday.t Time-HiRes-1.9732/t/itimer.t Time-HiRes-1.9732/t/nanosleep.t Time-HiRes-1.9732/t/sleep.t Time-HiRes-1.9732/t/stat.t Time-HiRes-1.9732/t/time.t Time-HiRes-1.9732/t/tv_interval.t Time-HiRes-1.9732/t/ualarm.t Time-HiRes-1.9732/t/usleep.t Time-HiRes-1.9732/t/Watchdog.pm Time-HiRes-1.9732/hints/aix.pl Time-HiRes-1.9732/hints/dec_osf.pl Time-HiRes-1.9732/hints/dynixptx.pl Time-HiRes-1.9732/hints/irix.pl Time-HiRes-1.9732/hints/linux.pl Time-HiRes-1.9732/hints/sco.pl Time-HiRes-1.9732/hints/solaris.pl Time-HiRes-1.9732/hints/svr4.pl Time-HiRes-1.9732/fallback/const-c.inc Time-HiRes-1.9732/fallback/const-xs.inc /bin/tar: Read 1536 bytes from - Configuring J/JH/JHI/Time-HiRes-1.9732.tar.gz with Makefile.PL >>> /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 Have /home/fly1800/cpanfly-5.18/var/megalib Want /home/fly1800/ap1800-297235/lib Your perl and your Config.pm seem to have different ideas about the architecture they are running on. Perl thinks: [megalib] Config says: [i686-linux-thread-multi-64int] This may or may not cause problems. Please check your installation of perl if you have problems building this extension. Generating a Unix-style Makefile Writing Makefile for Time::HiRes Writing MYMETA.yml and MYMETA.json Now you may issue 'make'. Do not forget also 'make test'. JHI/Time-HiRes-1.9732.tar.gz /home/fly1800/ap1800-297235/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9732.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/home/fly1800/ap1800-297235/bin/perl-static" "/home/fly1800/cpanfly-5.18/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.9732\" -DXS_VERSION=\"1.9732\" -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 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 "/home/fly1800/ap1800-297235/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Use of uninitialized value $dirs[0] in string eq at /home/fly1800/cpanfly-5.18/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /home/fly1800/cpanfly-5.18/var/megalib/Pod/Man.pm line 894. Manifying 1 pod document JHI/Time-HiRes-1.9732.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" PERL_DL_NONLAZY=1 "/home/fly1800/ap1800-297235/bin/perl-static" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/alarm.t ......... 1..10 # I am the main process 7368, starting the watchdog process... # I am the watchdog process 7369, sleeping for 360 seconds... # The watchdog process 7369 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300884 # Select returned! 4 0.300974 # 0.899675333333333 # Tick! 3 0.601862 # Select returned! 3 0.601979 # 0.799340333333333 # Tick! 2 0.90278 # Select returned! 2 0.902868 # 0.699044 # Tick! 1 1.203726 # Select returned! 1 1.203801 # 0.598733 # Tick! 0 1.504684 # Select returned! 0 1.504792 # 0.498402666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1457953582.09682, t0 = 1457953582.09672, dt = 9.918212890625e-05 # N = 2048, t1 = 1457953582.09708, t0 = 1457953582.09688, dt = 0.000193119049072266 # N = 4096, t1 = 1457953582.09715, t0 = 1457953582.09711, dt = 3.69548797607422e-05 # N = 8192, t1 = 1457953582.0979, t0 = 1457953582.09715, dt = 0.000748872756958008 # N = 16384, t1 = 1457953582.09957, t0 = 1457953582.09795, dt = 0.00162005424499512 # N = 32768, t1 = 1457953582.10395, t0 = 1457953582.09962, dt = 0.00433087348937988 # N = 65536, t1 = 1457953582.1105, t0 = 1457953582.10401, dt = 0.00649380683898926 # N = 131072, t1 = 1457953582.12367, t0 = 1457953582.11055, dt = 0.0131180286407471 # Alarm 1 - 1457953582.13473 # Alarm 2 - 1457953582.16017 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 7368, terminating the watchdog process 7369 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 7369 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 7370, starting the watchdog process... # I am the watchdog process 7371, sleeping for 360 seconds... # The watchdog process 7371 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 = 1457953587.85471, t0 = 1457953586.35144 # dt = 1.50327563285828, rt = 0.00218375523885084 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 7370, terminating the watchdog process 7371 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 7371 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 7372, starting the watchdog process... # I am the watchdog process 7373, sleeping for 360 seconds... # The watchdog process 7373 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 7372, terminating the watchdog process 7373 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7373 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 7374, starting the watchdog process... # I am the watchdog process 7375, sleeping for 360 seconds... # The watchdog process 7375 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.500923 0.400939 # Tick! 2 0.508296 # Tick! 1 0.91537 # Tick! 0 1.319237 # Tick! 0 1.724037 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 7374, terminating the watchdog process 7375 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 7375 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 7376, starting the watchdog process... # I am the watchdog process 7377, sleeping for 360 seconds... # The watchdog process 7377 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 7376, terminating the watchdog process 7377 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7377 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 7378, starting the watchdog process... # I am the watchdog process 7379, sleeping for 360 seconds... # The watchdog process 7379 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1457953593.08952 ok 3 # sleep...0.502285 ok 4 # I am the main process 7378, terminating the watchdog process 7379 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7379 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 7380, starting the watchdog process... # I am the watchdog process 7381, sleeping for 360 seconds... # The watchdog process 7381 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 = 1457953593 1457953594 1457953594 1457953594 1457953595 # atime = 1457953594 1457953594 1457953594 1457953594 1457953595 # 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 7380, terminating the watchdog process 7381 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 7381 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 7382, starting the watchdog process... # I am the watchdog process 7383, sleeping for 360 seconds... # The watchdog process 7383 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 26.8467910289764, n = 100, s/n = 0.268467910289764 # I am the main process 7382, terminating the watchdog process 7383 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7383 = 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 7385, starting the watchdog process... # I am the watchdog process 7386, sleeping for 360 seconds... # The watchdog process 7386 launched, continuing testing... ok 1 # tick = 1, one = 1457953595, two = 1457953595, three = 1457953595 ok 2 # tick = 3, one = 1457953595, two = 1457953595, three = 1457953595 ok 3 - negative time error # t0 = 1457953595.41933 # ualarm(100000) # t1 = 1457953595.52156 # dt = 0.102231025695801 # r = 1.02231025695801 ok 4 # t0 = 1457953595.52283 # ualarm(1100000) # t1 = 1457953596.62536 # dt = 1.1025230884552 # r = 1.00229371677745 ok 5 # t0 = 1457953596.62667 # ualarm(2200000) # t1 = 1457953598.82931 # dt = 2.20264005661011 # r = 1.00120002573187 ok 6 # t0 = 1457953598.83082 # ualarm(4300000) # t1 = 1457953603.13338 # dt = 4.30255389213562 # r = 1.00059392840363 ok 7 # t0 = 1457953603.13515 # got0 = 0 # t1 = 1457953603.43515 # t1 - t0 = 0.300000905990601 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 7385, terminating the watchdog process 7386 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 7386 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 7387, starting the watchdog process... # I am the watchdog process 7388, sleeping for 360 seconds... # The watchdog process 7388 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 7387, terminating the watchdog process 7388 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 7388 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.06 usr 0.04 sys + 12.48 cusr 2.24 csys = 14.82 CPU) Result: PASS JHI/Time-HiRes-1.9732.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1800/cpanfly-5.18/var/cpan/build/Time-HiRes-1.9732-Us93ic && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/home/fly1800/cpanfly-5.18/var/REPO/J/JH/JHI/Time-HiRes-1.9732.tar.gz Time-HiRes-1.9732.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 >>> mv /home/fly1800/cpanfly-5.18/var/cpan/build/Time-HiRes-1.9732-Us93ic/Time-HiRes-1.9732.ppd /home/fly1800/cpanfly-5.18/var/REPO/J/JH/JHI VmSize: 154056 kB Finished 2016-03-14T04:06:47