PATH=/usr/bin:/bin:/Users/fly2200/cpanfly-5.22/var/megalib/bin Start 2016-10-22T00:07:43 ActivePerl-2200 CPAN-2.10 Reading '/Users/fly2200/cpanfly-5.22/var/cpan/Metadata' Database was generated on Fri, 21 Oct 2016 05:17:02 GMT Checksum for /Users/fly2200/cpanfly-5.22/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9740.tar.gz ok Time-HiRes-1.9740/ Time-HiRes-1.9740/Changes Time-HiRes-1.9740/fallback/ Time-HiRes-1.9740/hints/ Time-HiRes-1.9740/HiRes.pm Time-HiRes-1.9740/HiRes.xs Time-HiRes-1.9740/Makefile.PL Time-HiRes-1.9740/MANIFEST Time-HiRes-1.9740/META.json Time-HiRes-1.9740/META.yml Time-HiRes-1.9740/ppport.h Time-HiRes-1.9740/README Time-HiRes-1.9740/t/ Time-HiRes-1.9740/TODO Time-HiRes-1.9740/typemap Time-HiRes-1.9740/t/alarm.t Time-HiRes-1.9740/t/clock.t Time-HiRes-1.9740/t/gettimeofday.t Time-HiRes-1.9740/t/itimer.t Time-HiRes-1.9740/t/nanosleep.t Time-HiRes-1.9740/t/sleep.t Time-HiRes-1.9740/t/stat.t Time-HiRes-1.9740/t/time.t Time-HiRes-1.9740/t/tv_interval.t Time-HiRes-1.9740/t/ualarm.t Time-HiRes-1.9740/t/usleep.t Time-HiRes-1.9740/t/utime.t Time-HiRes-1.9740/t/Watchdog.pm Time-HiRes-1.9740/hints/aix.pl Time-HiRes-1.9740/hints/dec_osf.pl Time-HiRes-1.9740/hints/dynixptx.pl Time-HiRes-1.9740/hints/irix.pl Time-HiRes-1.9740/hints/linux.pl Time-HiRes-1.9740/hints/sco.pl Time-HiRes-1.9740/hints/solaris.pl Time-HiRes-1.9740/hints/svr4.pl Time-HiRes-1.9740/fallback/const-c.inc Time-HiRes-1.9740/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9740.tar.gz with Makefile.PL >>> /Users/fly2200/ap2200-299195/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/fly2200/cpanfly-5.22/var/megalib Want /Users/fly2200/ap2200-299195/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.9740.tar.gz /Users/fly2200/ap2200-299195/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9740.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/Users/fly2200/ap2200-299195/bin/perl-dynamic" "/Users/fly2200/cpanfly-5.22/var/megalib/ExtUtils/xsubpp" -typemap "/Users/fly2200/ap2200-299195/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-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9740\" -DXS_VERSION=\"1.9740\" "-I/Users/fly2200/ap2200-299195/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETTIME_EMULATION -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_GETRES_EMULATION -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK_NANOSLEEP_EMULATION -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT_ST_XTIMESPEC -DTIME_HIRES_STAT=1 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.bundle gcc -mmacosx-version-min=10.9 -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/fly2200/ap2200-299195/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.9740.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/fly2200/ap2200-299195/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 46596, starting the watchdog process... # I am the watchdog process 46597, sleeping for 360 seconds... # The watchdog process 46597 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.302294 # Select returned! 4 0.302369 # 0.899210333333333 # Tick! 3 0.604588 # Select returned! 3 0.604645 # 0.798451666666667 # Tick! 2 0.905949 # Select returned! 2 0.906022 # 0.697992666666667 # Tick! 1 1.208082 # Select returned! 1 1.208138 # 0.597287333333333 # Tick! 0 1.510435 # Select returned! 0 1.510503 # 0.496499 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1477120070.60741, t0 = 1477120070.60737, dt = 4.00543212890625e-05 # N = 2048, t1 = 1477120070.60752, t0 = 1477120070.60744, dt = 7.79628753662109e-05 # N = 4096, t1 = 1477120070.60768, t0 = 1477120070.60753, dt = 0.000154018402099609 # N = 8192, t1 = 1477120070.608, t0 = 1477120070.60769, dt = 0.000318050384521484 # N = 16384, t1 = 1477120070.60864, t0 = 1477120070.60801, dt = 0.000626802444458008 # N = 32768, t1 = 1477120070.60989, t0 = 1477120070.60864, dt = 0.00124716758728027 # N = 65536, t1 = 1477120070.61239, t0 = 1477120070.6099, dt = 0.00249314308166504 # N = 131072, t1 = 1477120070.61801, t0 = 1477120070.6124, dt = 0.00560903549194336 # N = 262144, t1 = 1477120070.62835, t0 = 1477120070.61802, dt = 0.0103299617767334 # Alarm 1 - 1477120070.64047 # Alarm 2 - 1477120070.66069 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 46596, terminating the watchdog process 46597 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 46597 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 46601, starting the watchdog process... # I am the watchdog process 46602, sleeping for 360 seconds... # The watchdog process 46602 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 = 1477120076.31885, t0 = 1477120074.81348 # dt = 1.50536561012268, rt = 0.00357707341512037 ok 2 ok 3 ok 4 # clock = 0.034649 # clock = 0.034649 0.071834 # clock = 0.034649 0.071834 0.108633 # clock = 0.034649 0.071834 0.108633 0.145889 ok 5 # I am the main process 46601, terminating the watchdog process 46602 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 46602 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 46603, starting the watchdog process... # I am the watchdog process 46604, sleeping for 360 seconds... # The watchdog process 46604 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 46603, terminating the watchdog process 46604 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 46604 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 46605, starting the watchdog process... # I am the watchdog process 46606, sleeping for 360 seconds... # The watchdog process 46606 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.498065 0.4 # Tick! 2 0.51446 # Tick! 1 0.925603 # Tick! 0 1.335985 # Tick! 0 1.746504 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 46605, terminating the watchdog process 46606 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 46606 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 46607, starting the watchdog process... # I am the watchdog process 46608, sleeping for 360 seconds... # The watchdog process 46608 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 46607, terminating the watchdog process 46608 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 46608 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 46609, starting the watchdog process... # The watchdog process 46610 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1477120081.35928 ok 3 # I am the watchdog process 46610, sleeping for 360 seconds... # sleep...0.502237 ok 4 # I am the main process 46609, terminating the watchdog process 46610 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 46610 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 46611, starting the watchdog process... # The watchdog process 46612 launched, continuing testing... # I am the watchdog process 46612, 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 = 1477120082 1477120082 1477120082 1477120083 1477120083 # atime = 1477120082 1477120082 1477120082 1477120083 1477120083 # 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 46611, terminating the watchdog process 46612 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 46612 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 46613, starting the watchdog process... # I am the watchdog process 46614, sleeping for 360 seconds... # The watchdog process 46614 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 58.9291610717773, n = 100, s/n = 0.589291610717773 # I am the main process 46613, terminating the watchdog process 46614 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 46614 = 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 46616, starting the watchdog process... # The watchdog process 46617 launched, continuing testing... # I am the watchdog process 46617, sleeping for 360 seconds... ok 1 # tick = 1, one = 1477120083, two = 1477120083, three = 1477120083 ok 2 # tick = 3, one = 1477120083, two = 1477120083, three = 1477120083 ok 3 - negative time error # t0 = 1477120083.71936 # ualarm(100000) # t1 = 1477120083.82152 # dt = 0.102167844772339 # r = 1.02167844772339 ok 4 # t0 = 1477120083.82197 # ualarm(1100000) # t1 = 1477120084.92408 # dt = 1.10211205482483 # r = 1.00192004984075 ok 5 # t0 = 1477120084.92483 # ualarm(2200000) # t1 = 1477120087.12708 # dt = 2.20224905014038 # r = 1.00102229551835 ok 6 # t0 = 1477120087.12732 # ualarm(4300000) # t1 = 1477120091.42948 # dt = 4.30215883255005 # r = 1.00050205408141 ok 7 # t0 = 1477120091.42979 # got0 = 0 # t1 = 1477120091.72979 # t1 - t0 = 0.300000190734863 # got1 = 200009 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 46616, terminating the watchdog process 46617 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 46617 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 46618, starting the watchdog process... # The watchdog process 46619 launched, continuing testing... # I am the watchdog process 46619, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 46618, terminating the watchdog process 46619 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 46619 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 26 wallclock secs ( 0.06 usr 0.02 sys + 14.10 cusr 0.13 csys = 14.31 CPU) Result: PASS JHI/Time-HiRes-1.9740.tar.gz make test TEST_VERBOSE=1 -- OK Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2200/cpanfly-5.22/var/tmp/cpan_build/Time-HiRes-1.9740-GErpxu && tar cvf - Time-HiRes-1.9740.ppd blib) | gzip -c >/Users/fly2200/cpanfly-5.22/var/REPO/J/JH/JHI/Time-HiRes-1.9740.tar.gz Time-HiRes-1.9740.ppd blib/ blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.bundle blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/man3/ blib/man3/Time::HiRes.3 Finished 2016-10-22T00:08:16