PATH=/usr/bin:/bin:/home/fly1400/cpanfly-5.14/var/megalib/bin Start 2016-04-24T12:12:44 ActivePerl-1400 CPAN-2.10 Reading '/home/fly1400/cpanfly-5.14/var/cpan/Metadata' Database was generated on Sun, 24 Apr 2016 04:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /home/fly1400/cpanfly-5.14/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz ok Time-HiRes-1.9733/ Time-HiRes-1.9733/Changes Time-HiRes-1.9733/fallback/ Time-HiRes-1.9733/hints/ Time-HiRes-1.9733/HiRes.pm Time-HiRes-1.9733/HiRes.xs Time-HiRes-1.9733/Makefile.PL Time-HiRes-1.9733/MANIFEST Time-HiRes-1.9733/META.json Time-HiRes-1.9733/META.yml Time-HiRes-1.9733/ppport.h Time-HiRes-1.9733/README Time-HiRes-1.9733/t/ Time-HiRes-1.9733/TODO Time-HiRes-1.9733/typemap Time-HiRes-1.9733/t/alarm.t Time-HiRes-1.9733/t/clock.t Time-HiRes-1.9733/t/gettimeofday.t Time-HiRes-1.9733/t/itimer.t Time-HiRes-1.9733/t/nanosleep.t Time-HiRes-1.9733/t/sleep.t Time-HiRes-1.9733/t/stat.t Time-HiRes-1.9733/t/time.t Time-HiRes-1.9733/t/tv_interval.t Time-HiRes-1.9733/t/ualarm.t Time-HiRes-1.9733/t/usleep.t Time-HiRes-1.9733/t/Watchdog.pm Time-HiRes-1.9733/hints/aix.pl Time-HiRes-1.9733/hints/dec_osf.pl Time-HiRes-1.9733/hints/dynixptx.pl Time-HiRes-1.9733/hints/irix.pl Time-HiRes-1.9733/hints/linux.pl Time-HiRes-1.9733/hints/sco.pl Time-HiRes-1.9733/hints/solaris.pl Time-HiRes-1.9733/hints/svr4.pl Time-HiRes-1.9733/fallback/const-c.inc Time-HiRes-1.9733/fallback/const-xs.inc /bin/tar: Read 2048 bytes from - Configuring J/JH/JHI/Time-HiRes-1.9733.tar.gz with Makefile.PL >>> /home/fly1400/ap1400/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/fly1400/cpanfly-5.14/var/megalib Want /home/fly1400/ap1400/lib Your perl and your Config.pm seem to have different ideas about the architecture they are running on. Perl thinks: [megalib] Config says: [x86_64-linux-thread-multi] 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 Now you may issue 'make'. Do not forget also 'make test'. JHI/Time-HiRes-1.9733.tar.gz /home/fly1400/ap1400/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9733.tar.gz Could not read metadata file. Falling back to other methods to determine prerequisites >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/home/fly1400/ap1400/bin/perl-static" "/home/fly1400/cpanfly-5.14/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly1400/ap1400/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 -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9733\" -DXS_VERSION=\"1.9733\" -fPIC "-I/home/fly1400/ap1400/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="/lib64" gcc -shared -O2 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so "/home/fly1400/ap1400/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/fly1400/cpanfly-5.14/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /home/fly1400/cpanfly-5.14/var/megalib/Pod/Man.pm line 894. Manifying 1 pod document JHI/Time-HiRes-1.9733.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/fly1400/ap1400/bin/perl-static" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(1, 'blib/lib', 'blib/arch')" t/*.t # Failed test at t/alarm.t line 109. # Looks like you failed 1 test of 10. t/alarm.t ......... 1..10 # I am the main process 19443, starting the watchdog process... # I am the watchdog process 19444, sleeping for 360 seconds... # The watchdog process 19444 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.610442 # Select returned! 0 0.610528 # 0.796490666666667 not ok 2 # tick: 0.3 sleep took 0.610442 ratio 2.03480666666667 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1461525173.42965, t0 = 1461525173.42954, dt = 0.000113010406494141 # N = 2048, t1 = 1461525173.42992, t0 = 1461525173.42972, dt = 0.000200033187866211 # N = 4096, t1 = 1461525173.43004, t0 = 1461525173.42996, dt = 8.17775726318359e-05 # N = 8192, t1 = 1461525173.43091, t0 = 1461525173.43011, dt = 0.00079798698425293 # N = 16384, t1 = 1461525173.43272, t0 = 1461525173.4312, dt = 0.00152587890625 # N = 32768, t1 = 1461525173.43571, t0 = 1461525173.43291, dt = 0.00280499458312988 # N = 65536, t1 = 1461525173.44235, t0 = 1461525173.43587, dt = 0.00648593902587891 # N = 131072, t1 = 1461525173.45623, t0 = 1461525173.44253, dt = 0.0136950016021729 # Alarm 1 - 1461525173.4682 # Alarm 2 - 1461525173.49654 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 19443, terminating the watchdog process 19444 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 19444 = 1 # All done. Dubious, test returned 1 (wstat 256, 0x100) Failed 1/10 subtests t/clock.t ......... 1..5 # I am the main process 19445, starting the watchdog process... # I am the watchdog process 19446, sleeping for 360 seconds... # The watchdog process 19446 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 = 1461525179.19722, t0 = 1461525177.6964 # dt = 1.50082397460938, rt = 0.00054931640625 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.12 # clock = 0.03 0.12 0.21 # clock = 0.03 0.12 0.21 0.3 ok 5 # I am the main process 19445, terminating the watchdog process 19446 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 19446 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 19447, starting the watchdog process... # I am the watchdog process 19448, sleeping for 360 seconds... # The watchdog process 19448 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19447, terminating the watchdog process 19448 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19448 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 19449, starting the watchdog process... # I am the watchdog process 19450, sleeping for 360 seconds... # The watchdog process 19450 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.500923 0.400939 # Tick! 2 0.504777 # Tick! 1 0.914271 # Tick! 0 1.31732 # Tick! 0 1.721552 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 19449, terminating the watchdog process 19450 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19450 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 19452, starting the watchdog process... # I am the watchdog process 19453, sleeping for 360 seconds... # The watchdog process 19453 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 19452, terminating the watchdog process 19453 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 19453 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 19454, starting the watchdog process... # I am the watchdog process 19455, sleeping for 360 seconds... # The watchdog process 19455 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1461525184.40559 ok 3 # sleep...0.500754 ok 4 # I am the main process 19454, terminating the watchdog process 19455 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19455 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 19456, starting the watchdog process... # I am the watchdog process 19457, sleeping for 360 seconds... # The watchdog process 19457 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 = 1461525185 1461525185 1461525185 1461525186 1461525186 # atime = 1461525185 1461525185 1461525185 1461525186 1461525186 # 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 19456, terminating the watchdog process 19457 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 19457 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 19458, starting the watchdog process... # I am the watchdog process 19459, sleeping for 360 seconds... # The watchdog process 19459 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 60.5345964431763, n = 100, s/n = 0.605345964431763 # I am the main process 19458, terminating the watchdog process 19459 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 19459 = 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 19461, starting the watchdog process... # I am the watchdog process 19462, sleeping for 360 seconds... # The watchdog process 19462 launched, continuing testing... ok 1 # tick = 1, one = 1461525186, two = 1461525186, three = 1461525186 ok 2 # tick = 3, one = 1461525186, two = 1461525186, three = 1461525186 ok 3 - negative time error # t0 = 1461525186.74678 # ualarm(100000) # t1 = 1461525186.84774 # dt = 0.100957870483398 # r = 1.00957870483398 ok 4 # t0 = 1461525186.84874 # ualarm(1100000) # t1 = 1461525187.94974 # dt = 1.10100197792053 # r = 1.00091088901867 ok 5 # t0 = 1461525187.95147 # ualarm(2200000) # t1 = 1461525190.15311 # dt = 2.20163416862488 # r = 1.0007428039204 ok 6 # t0 = 1461525190.15426 # ualarm(4300000) # t1 = 1461525194.45541 # dt = 4.30114912986755 # r = 1.00026723950408 ok 7 # t0 = 1461525194.4565 # got0 = 0 # t1 = 1461525194.7565 # t1 - t0 = 0.300000905990601 # got1 = 200005 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 19461, terminating the watchdog process 19462 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 19462 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 19463, starting the watchdog process... # I am the watchdog process 19464, sleeping for 360 seconds... # The watchdog process 19464 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 19463, terminating the watchdog process 19464 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 19464 = 1 # All done. ok Test Summary Report ------------------- t/alarm.t (Wstat: 256 Tests: 10 Failed: 1) Failed test: 2 Non-zero exit status: 1 Files=11, Tests=95, 26 wallclock secs ( 0.07 usr 0.04 sys + 14.56 cusr 0.13 csys = 14.80 CPU) Result: FAIL Failed 1/11 test programs. 1/95 subtests failed. make: *** [test_dynamic] Error 255 JHI/Time-HiRes-1.9733.tar.gz make test TEST_VERBOSE=1 -- NOT OK //hint// to see the cpan-testers results for installing this module, try: reports JHI/Time-HiRes-1.9733.tar.gz VmSize: 271812 kB Finished 2016-04-24T12:13:18