PATH=/usr/bin:/bin:/Users/fly2400/cpanfly-5.24/var/megalib/bin Start 2017-08-17T08:12:29 ActivePerl-2400 CPAN-2.10 Reading '/Users/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Thu, 17 Aug 2017 05:17:03 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9745.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /Users/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9745.tar.gz ok Time-HiRes-1.9745/ Time-HiRes-1.9745/Changes Time-HiRes-1.9745/fallback/ Time-HiRes-1.9745/hints/ Time-HiRes-1.9745/HiRes.pm Time-HiRes-1.9745/HiRes.xs Time-HiRes-1.9745/Makefile.PL Time-HiRes-1.9745/MANIFEST Time-HiRes-1.9745/META.json Time-HiRes-1.9745/META.yml Time-HiRes-1.9745/ppport.h Time-HiRes-1.9745/README Time-HiRes-1.9745/t/ Time-HiRes-1.9745/TODO Time-HiRes-1.9745/typemap Time-HiRes-1.9745/t/alarm.t Time-HiRes-1.9745/t/clock.t Time-HiRes-1.9745/t/gettimeofday.t Time-HiRes-1.9745/t/itimer.t Time-HiRes-1.9745/t/nanosleep.t Time-HiRes-1.9745/t/sleep.t Time-HiRes-1.9745/t/stat.t Time-HiRes-1.9745/t/time.t Time-HiRes-1.9745/t/tv_interval.t Time-HiRes-1.9745/t/ualarm.t Time-HiRes-1.9745/t/usleep.t Time-HiRes-1.9745/t/utime.t Time-HiRes-1.9745/t/Watchdog.pm Time-HiRes-1.9745/hints/aix.pl Time-HiRes-1.9745/hints/dec_osf.pl Time-HiRes-1.9745/hints/dynixptx.pl Time-HiRes-1.9745/hints/irix.pl Time-HiRes-1.9745/hints/linux.pl Time-HiRes-1.9745/hints/sco.pl Time-HiRes-1.9745/hints/solaris.pl Time-HiRes-1.9745/hints/svr4.pl Time-HiRes-1.9745/fallback/const-c.inc Time-HiRes-1.9745/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9745.tar.gz with Makefile.PL >>> /Users/fly2400/ap2400-300558/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 clockid_t... NOT found, will use int. 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 working futimens()... NOT found. Looking for working utimensat()... NOT found. You do NOT seem to have subsecond timestamp setting. 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 subsecond timestamp reading. (Your struct stat has them, but the filesystems must help.) However, you do NOT seem to have subsecond timestamp setting. This is a curious combination. Checking if your kit is complete... Looks good Have /Users/fly2400/cpanfly-5.24/var/megalib Want /Users/fly2400/ap2400-300558/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.9745.tar.gz /Users/fly2400/ap2400-300558/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9745.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for HiRes () chmod 644 "HiRes.bs" "/Users/fly2400/ap2400-300558/bin/perl-dynamic" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 "/Users/fly2400/ap2400-300558/bin/perl-dynamic" "/Users/fly2400/cpanfly-5.24/var/megalib/ExtUtils/xsubpp" -typemap '/Users/fly2400/ap2400-300558/lib/ExtUtils/typemap' -typemap '/Users/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9745-YV0rgJ/typemap' HiRes.xs > HiRes.xsc mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -mmacosx-version-min=10.9 -arch x86_64 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-strict-aliasing -pipe -fstack-protector -DPERL_USE_SAFE_PUTENV -O3 -DVERSION=\"1.9745\" -DXS_VERSION=\"1.9745\" "-I/Users/fly2400/ap2400-300558/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 -o blib/arch/auto/Time/HiRes/HiRes.bundle HiRes.o \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle Manifying 1 pod document JHI/Time-HiRes-1.9745.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 "/Users/fly2400/ap2400-300558/bin/perl-dynamic" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 PERL_DL_NONLAZY=1 "/Users/fly2400/ap2400-300558/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 82051, starting the watchdog process... # The watchdog process 82052 launched, continuing testing... # I am the watchdog process 82052, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.303542 # Select returned! 4 0.30363 # 0.89879 # Tick! 3 0.606985 # Select returned! 3 0.607053 # 0.797649 # Tick! 2 0.910415 # Select returned! 2 0.910464 # 0.696512 # Tick! 1 1.21406 # Select returned! 1 1.214153 # 0.595282333333333 # Tick! 0 1.51753 # Select returned! 0 1.517596 # 0.494134666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1502982755.47445, t0 = 1502982755.47441, dt = 4.00543212890625e-05 # N = 2048, t1 = 1502982755.4746, t0 = 1502982755.47448, dt = 0.000121831893920898 # N = 4096, t1 = 1502982755.47482, t0 = 1502982755.47462, dt = 0.000200986862182617 # N = 8192, t1 = 1502982755.47519, t0 = 1502982755.47485, dt = 0.00034022331237793 # N = 16384, t1 = 1502982755.47576, t0 = 1502982755.47523, dt = 0.000535964965820312 # N = 32768, t1 = 1502982755.47685, t0 = 1502982755.47579, dt = 0.00105881690979004 # N = 65536, t1 = 1502982755.47898, t0 = 1502982755.47687, dt = 0.00210404396057129 # N = 131072, t1 = 1502982755.4832, t0 = 1502982755.479, dt = 0.00419497489929199 # N = 262144, t1 = 1502982755.49164, t0 = 1502982755.48322, dt = 0.00841712951660156 # N = 524288, t1 = 1502982755.5085, t0 = 1502982755.49167, dt = 0.0168330669403076 # Alarm 1 - 1502982755.51962 # Alarm 2 - 1502982755.55146 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 82051, terminating the watchdog process 82052 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 82052 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 82053, starting the watchdog process... # The watchdog process 82054 launched, continuing testing... # I am the watchdog process 82054, 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 # t1 = 1502982761.29212, t0 = 1502982759.78554 # dt = 1.50658464431763, rt = 0.00438976287841797 ok 2 ok 3 ok 4 # clock = 0.040439 # clock = 0.040439 0.070521 # clock = 0.040439 0.070521 0.100273 # clock = 0.040439 0.070521 0.100273 0.129996 ok 5 # I am the main process 82053, terminating the watchdog process 82054 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 82054 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 82055, starting the watchdog process... # The watchdog process 82056 launched, continuing testing... # I am the watchdog process 82056, 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 82055, terminating the watchdog process 82056 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 82056 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 82057, starting the watchdog process... # The watchdog process 82058 launched, continuing testing... # I am the watchdog process 82058, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.499773 0.4 # Tick! 2 0.519548 # Tick! 1 0.93659 # Tick! 0 1.354058 # Tick! 0 1.771872 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 82057, terminating the watchdog process 82058 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 82058 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 82059, starting the watchdog process... # The watchdog process 82060 launched, continuing testing... # I am the watchdog process 82060, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 82059, terminating the watchdog process 82060 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 82060 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 82061, starting the watchdog process... # The watchdog process 82062 launched, continuing testing... # I am the watchdog process 82062, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1502982766.36859 ok 3 # sleep...0.501531 ok 4 # I am the main process 82061, terminating the watchdog process 82062 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 82062 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 82063, starting the watchdog process... # The watchdog process 82064 launched, continuing testing... # I am the watchdog process 82064, 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 = 1502982767 1502982767 1502982767 1502982768 1502982768 # atime = 1502982767 1502982767 1502982767 1502982768 1502982768 # 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 82063, terminating the watchdog process 82064 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 82064 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 82065, starting the watchdog process... # The watchdog process 82066 launched, continuing testing... # I am the watchdog process 82066, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 62.503326177597, n = 100, s/n = 0.62503326177597 # I am the main process 82065, terminating the watchdog process 82066 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 82066 = 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 82068, starting the watchdog process... # The watchdog process 82069 launched, continuing testing... # I am the watchdog process 82069, sleeping for 360 seconds... ok 1 # tick = 1, one = 1502982768, two = 1502982768, three = 1502982768 ok 2 # tick = 3, one = 1502982768, two = 1502982768, three = 1502982768 ok 3 - negative time error # t0 = 1502982768.76547 # ualarm(100000) # t1 = 1502982768.8666 # dt = 0.101130962371826 # r = 1.01130962371826 ok 4 # t0 = 1502982768.86684 # ualarm(1100000) # t1 = 1502982769.96992 # dt = 1.10308289527893 # r = 1.00280263207176 ok 5 # t0 = 1502982769.97024 # ualarm(2200000) # t1 = 1502982772.17358 # dt = 2.20334887504578 # r = 1.0015222159299 ok 6 # t0 = 1502982772.17391 # ualarm(4300000) # t1 = 1502982776.47536 # dt = 4.30145406723022 # r = 1.00033815516982 ok 7 # t0 = 1502982776.47561 # got0 = 0 # t1 = 1502982776.77561 # t1 - t0 = 0.30000114440918 # got1 = 199998 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 82068, terminating the watchdog process 82069 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 82069 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 82070, starting the watchdog process... # The watchdog process 82071 launched, continuing testing... # I am the watchdog process 82071, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 82070, terminating the watchdog process 82071 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 82071 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 27 wallclock secs ( 0.06 usr 0.02 sys + 14.57 cusr 0.13 csys = 14.78 CPU) Result: PASS JHI/Time-HiRes-1.9745.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9745-YV0rgJ && tar cvf - Time-HiRes-1.9745.ppd blib) | gzip -c >/Users/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9745.tar.gz Time-HiRes-1.9745.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 2017-08-17T08:13:01