PATH=/usr/bin:/bin:/Users/fly2000/cpanfly-5.20/var/megalib/bin Start 2016-06-21T10:02:21 ActivePerl-2000 CPAN-2.10 Reading '/Users/fly2000/cpanfly-5.20/var/cpan/Metadata' Database was generated on Tue, 21 Jun 2016 05:29:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9735.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /Users/fly2000/cpanfly-5.20/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9735.tar.gz ok Time-HiRes-1.9735/ Time-HiRes-1.9735/Changes Time-HiRes-1.9735/fallback/ Time-HiRes-1.9735/hints/ Time-HiRes-1.9735/HiRes.pm Time-HiRes-1.9735/HiRes.xs Time-HiRes-1.9735/Makefile.PL Time-HiRes-1.9735/MANIFEST Time-HiRes-1.9735/META.json Time-HiRes-1.9735/META.yml Time-HiRes-1.9735/ppport.h Time-HiRes-1.9735/README Time-HiRes-1.9735/t/ Time-HiRes-1.9735/TODO Time-HiRes-1.9735/typemap Time-HiRes-1.9735/t/alarm.t Time-HiRes-1.9735/t/clock.t Time-HiRes-1.9735/t/gettimeofday.t Time-HiRes-1.9735/t/itimer.t Time-HiRes-1.9735/t/nanosleep.t Time-HiRes-1.9735/t/sleep.t Time-HiRes-1.9735/t/stat.t Time-HiRes-1.9735/t/time.t Time-HiRes-1.9735/t/tv_interval.t Time-HiRes-1.9735/t/ualarm.t Time-HiRes-1.9735/t/usleep.t Time-HiRes-1.9735/t/utime.t Time-HiRes-1.9735/t/Watchdog.pm Time-HiRes-1.9735/hints/aix.pl Time-HiRes-1.9735/hints/dec_osf.pl Time-HiRes-1.9735/hints/dynixptx.pl Time-HiRes-1.9735/hints/irix.pl Time-HiRes-1.9735/hints/linux.pl Time-HiRes-1.9735/hints/sco.pl Time-HiRes-1.9735/hints/solaris.pl Time-HiRes-1.9735/hints/svr4.pl Time-HiRes-1.9735/fallback/const-c.inc Time-HiRes-1.9735/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9735.tar.gz with Makefile.PL >>> /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL Configuring Time::HiRes... 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 (via emulation). Looking for clock_getres()... found (via emulation). Looking for clock_nanosleep()... found (via emulation). Looking for clock()... found. Looking for futimens()... NOT found. Looking for utimensat()... NOT found. Looking for stat() subsecond timestamps... Trying struct stat st_atimespec.tv_nsec...found. Trying struct stat st_atimensec...NOT found. Trying struct stat st_atime_n...NOT found. Trying struct stat st_atim.tv_nsec...NOT found. Trying struct stat st_uatime...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 /Users/fly2000/cpanfly-5.20/var/megalib Want /Users/fly2000/ap2000-298465/lib Your perl and your Config.pm seem to have different ideas about the architecture they are running on. Perl thinks: [megalib] Config says: [darwin-thread-multi-2level] 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.9735.tar.gz /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9735.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/Users/fly2000/ap2000-298465/bin/perl-dynamic" "/Users/fly2000/cpanfly-5.20/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly2000/ap2000-298465/lib/ExtUtils/typemap" -typemap "typemap" HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -arch x86_64 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9735\" -DXS_VERSION=\"1.9735\" "-I/Users/fly2000/ap2000-298465/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT_ST_XTIMESPEC -DTIME_HIRES_STAT=1 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.bundle cc -mmacosx-version-min=10.5 -bundle -undefined dynamic_lookup -Wl,-search_paths_first -arch x86_64 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle "/Users/fly2000/ap2000-298465/bin/perl-dynamic" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Manifying 1 pod document JHI/Time-HiRes-1.9735.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 "/Users/fly2000/ap2000-298465/bin/perl-dynamic" "-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 79278, starting the watchdog process... # The watchdog process 79279 launched, continuing testing... # I am the watchdog process 79279, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300384 # Select returned! 4 0.300578 # 0.899807333333333 # Tick! 3 0.600917 # Select returned! 3 0.601065 # 0.799645 # Tick! 2 0.901373 # Select returned! 2 0.901549 # 0.699483666666667 # Tick! 1 1.201934 # Select returned! 1 1.202108 # 0.599297333333333 # Tick! 0 1.502446 # Select returned! 0 1.502599 # 0.499133666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1466528551.82669, t0 = 1466528551.8266, dt = 9.01222229003906e-05 # N = 2048, t1 = 1466528551.82694, t0 = 1466528551.82676, dt = 0.000174045562744141 # N = 4096, t1 = 1466528551.82732, t0 = 1466528551.82698, dt = 0.000342130661010742 # N = 8192, t1 = 1466528551.82801, t0 = 1466528551.82736, dt = 0.000651121139526367 # N = 16384, t1 = 1466528551.82921, t0 = 1466528551.82805, dt = 0.00116395950317383 # N = 32768, t1 = 1466528551.83159, t0 = 1466528551.82924, dt = 0.0023500919342041 # N = 65536, t1 = 1466528551.8362, t0 = 1466528551.83163, dt = 0.00457096099853516 # N = 131072, t1 = 1466528551.84478, t0 = 1466528551.83623, dt = 0.00854897499084473 # N = 262144, t1 = 1466528551.86204, t0 = 1466528551.84481, dt = 0.0172250270843506 # Alarm 1 - 1466528551.87214 # Alarm 2 - 1466528551.9117 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 79278, terminating the watchdog process 79279 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 79279 = 1 # All done. ok panic: MUTEX_LOCK (22) [HiRes.xs:773] at t/clock.t line 40. # Looks like you planned 5 tests but ran 1. # Looks like your test exited with 255 just after 1. t/clock.t ......... 1..5 # I am the main process 79280, starting the watchdog process... # The watchdog process 79281 launched, continuing testing... # I am the watchdog process 79281, sleeping for 360 seconds... ok 1 - require Time::HiRes; # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # CLOCK_REALTIME: try = 1 # I am the main process 79280, terminating the watchdog process 79281 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 79281 = 1 # All done. Dubious, test returned 255 (wstat 65280, 0xff00) Failed 4/5 subtests t/gettimeofday.t .. 1..6 # I am the main process 79282, starting the watchdog process... # The watchdog process 79283 launched, continuing testing... # I am the watchdog process 79283, sleeping for 360 seconds... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 79282, terminating the watchdog process 79283 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 79283 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 79284, starting the watchdog process... # The watchdog process 79285 launched, continuing testing... # setitimer: 0 0 # I am the watchdog process 79285, sleeping for 360 seconds... ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497468 0.4 # Tick! 2 0.509289 # Tick! 1 0.916246 # Tick! 0 1.323614 # Tick! 0 1.731014 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 79284, terminating the watchdog process 79285 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 79285 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 79286, starting the watchdog process... # The watchdog process 79287 launched, continuing testing... # I am the watchdog process 79287, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 79286, terminating the watchdog process 79287 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 79287 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 79288, starting the watchdog process... # The watchdog process 79289 launched, continuing testing... # I am the watchdog process 79289, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1466528559.6119 ok 3 # sleep...0.500113 ok 4 # I am the main process 79288, terminating the watchdog process 79289 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 79289 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 79290, starting the watchdog process... # The watchdog process 79291 launched, continuing testing... # I am the watchdog process 79291, sleeping for 360 seconds... 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 = 1466528560 1466528560 1466528560 1466528561 1466528561 # atime = 1466528560 1466528560 1466528561 1466528561 1466528561 # 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 79290, terminating the watchdog process 79291 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 79291 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 79292, starting the watchdog process... # The watchdog process 79293 launched, continuing testing... # I am the watchdog process 79293, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 67.3189096450806, n = 100, s/n = 0.673189096450806 # I am the main process 79292, terminating the watchdog process 79293 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 79293 = 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 79295, starting the watchdog process... # The watchdog process 79296 launched, continuing testing... # I am the watchdog process 79296, sleeping for 360 seconds... ok 1 # tick = 1, one = 1466528561, two = 1466528561, three = 1466528561 ok 2 # tick = 3, one = 1466528561, two = 1466528561, three = 1466528561 ok 3 - negative time error # t0 = 1466528561.81613 # ualarm(100000) # t1 = 1466528561.91626 # dt = 0.100131988525391 # r = 1.00131988525391 ok 4 # t0 = 1466528561.91656 # ualarm(1100000) # t1 = 1466528563.01663 # dt = 1.10007405281067 # r = 1.00006732073697 ok 5 # t0 = 1466528563.01698 # ualarm(2200000) # t1 = 1466528565.21705 # dt = 2.20006990432739 # r = 1.00003177469427 ok 6 # t0 = 1466528565.21741 # ualarm(4300000) # t1 = 1466528569.51745 # dt = 4.30003714561462 # r = 1.00000863851503 ok 7 # t0 = 1466528569.51783 # got0 = 0 # t1 = 1466528569.81783 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 79295, terminating the watchdog process 79296 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 79296 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 79297, starting the watchdog process... # The watchdog process 79298 launched, continuing testing... # I am the watchdog process 79298, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 79297, terminating the watchdog process 79298 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 79298 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime() Test Summary Report ------------------- t/clock.t (Wstat: 65280 Tests: 1 Failed: 0) Non-zero exit status: 255 Parse errors: Bad plan. You planned 5 tests but ran 1. Files=12, Tests=91, 23 wallclock secs ( 0.10 usr 0.04 sys + 14.46 cusr 0.16 csys = 14.76 CPU) Result: FAIL Failed 1/12 test programs. 0/91 subtests failed. make: *** [test_dynamic] Error 255 JHI/Time-HiRes-1.9735.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.9735.tar.gz Finished 2016-06-21T10:02:53