PATH=/usr/bin:/bin:/Users/fly2400/cpanfly-5.24/var/megalib/bin Start 2018-01-13T02:02:44 ActivePerl-2400 CPAN-2.10 Reading '/Users/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Fri, 12 Jan 2018 05:54:55 GMT Checksum for /Users/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9753.tar.gz ok Time-HiRes-1.9753/ Time-HiRes-1.9753/HiRes.xs Time-HiRes-1.9753/fallback/ Time-HiRes-1.9753/Changes Time-HiRes-1.9753/HiRes.pm Time-HiRes-1.9753/MANIFEST Time-HiRes-1.9753/ppport.h Time-HiRes-1.9753/t/ Time-HiRes-1.9753/README Time-HiRes-1.9753/TODO Time-HiRes-1.9753/typemap Time-HiRes-1.9753/META.yml Time-HiRes-1.9753/Makefile.PL Time-HiRes-1.9753/hints/ Time-HiRes-1.9753/META.json Time-HiRes-1.9753/hints/dynixptx.pl Time-HiRes-1.9753/hints/sco.pl Time-HiRes-1.9753/hints/svr4.pl Time-HiRes-1.9753/hints/dec_osf.pl Time-HiRes-1.9753/hints/solaris.pl Time-HiRes-1.9753/hints/aix.pl Time-HiRes-1.9753/hints/linux.pl Time-HiRes-1.9753/hints/irix.pl Time-HiRes-1.9753/t/utime.t Time-HiRes-1.9753/t/time.t Time-HiRes-1.9753/t/sleep.t Time-HiRes-1.9753/t/tv_interval.t Time-HiRes-1.9753/t/itimer.t Time-HiRes-1.9753/t/Watchdog.pm Time-HiRes-1.9753/t/alarm.t Time-HiRes-1.9753/t/stat.t Time-HiRes-1.9753/t/nanosleep.t Time-HiRes-1.9753/t/gettimeofday.t Time-HiRes-1.9753/t/usleep.t Time-HiRes-1.9753/t/clock.t Time-HiRes-1.9753/t/ualarm.t Time-HiRes-1.9753/fallback/const-c.inc Time-HiRes-1.9753/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9753.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. 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.9753.tar.gz /Users/fly2400/ap2400-300558/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9753.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.9753-VcYBbR/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.9753\" -DXS_VERSION=\"1.9753\" "-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.9753.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 67300, starting the watchdog process... # The watchdog process 67301 launched, continuing testing... # I am the watchdog process 67301, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.30373 # Select returned! 4 0.303812 # 0.898729333333333 # Tick! 3 0.60734 # Select returned! 3 0.607406 # 0.797531333333333 # Tick! 2 0.910655 # Select returned! 2 0.910737 # 0.696421 # Tick! 1 1.211321 # Select returned! 1 1.211386 # 0.596204666666667 # Tick! 0 1.51484 # Select returned! 0 1.514907 # 0.495031 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1515837770.59581, t0 = 1515837770.59575, dt = 5.50746917724609e-05 # N = 2048, t1 = 1515837770.5959, t0 = 1515837770.59584, dt = 6.31809234619141e-05 # N = 4096, t1 = 1515837770.59604, t0 = 1515837770.5959, dt = 0.000133991241455078 # N = 8192, t1 = 1515837770.59632, t0 = 1515837770.59607, dt = 0.000257015228271484 # N = 16384, t1 = 1515837770.59685, t0 = 1515837770.59635, dt = 0.000499963760375977 # N = 32768, t1 = 1515837770.59786, t0 = 1515837770.59687, dt = 0.000985145568847656 # N = 65536, t1 = 1515837770.59989, t0 = 1515837770.59789, dt = 0.00200390815734863 # N = 131072, t1 = 1515837770.60382, t0 = 1515837770.59992, dt = 0.00390887260437012 # N = 262144, t1 = 1515837770.61168, t0 = 1515837770.60385, dt = 0.00782394409179688 # N = 524288, t1 = 1515837770.62756, t0 = 1515837770.61171, dt = 0.0158481597900391 # Alarm 1 - 1515837770.63871 # Alarm 2 - 1515837770.67076 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 67300, terminating the watchdog process 67301 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 67301 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 67302, starting the watchdog process... # The watchdog process 67303 launched, continuing testing... # I am the watchdog process 67303, 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 = 1515837776.41266, t0 = 1515837774.90606 # dt = 1.50660157203674, rt = 0.00440104802449537 ok 2 ok 3 ok 4 # CLOCKS_PER_SEC = 1000000 # delay = 1000000 # clock = 0.052041 # clock = 0.052041 0.082009 # clock = 0.052041 0.082009 0.111815 # clock = 0.052041 0.082009 0.111815 0.141619 ok 5 # I am the main process 67302, terminating the watchdog process 67303 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 67303 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 67307, starting the watchdog process... # The watchdog process 67308 launched, continuing testing... # I am the watchdog process 67308, 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 67307, terminating the watchdog process 67308 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67308 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 67309, starting the watchdog process... # The watchdog process 67310 launched, continuing testing... # I am the watchdog process 67310, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.499765 0.4 # Tick! 2 0.521391 # Tick! 1 0.936944 # Tick! 0 1.352303 # Tick! 0 1.761928 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 67309, terminating the watchdog process 67310 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67310 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 67311, starting the watchdog process... # The watchdog process 67312 launched, continuing testing... # I am the watchdog process 67312, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 - nanosleep for more than 0.4 sec ok 4 # I am the main process 67311, terminating the watchdog process 67312 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67312 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 67313, starting the watchdog process... # The watchdog process 67314 launched, continuing testing... # I am the watchdog process 67314, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1515837781.52274 ok 3 # sleep...0.503323 ok 4 # I am the main process 67313, terminating the watchdog process 67314 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67314 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 67315, starting the watchdog process... # The watchdog process 67316 launched, continuing testing... # I am the watchdog process 67316, 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 = 1515837782 1515837782 1515837782 1515837783 1515837783 # atime = 1515837782 1515837782 1515837782 1515837783 1515837783 # 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 67315, terminating the watchdog process 67316 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67316 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 67317, starting the watchdog process... # The watchdog process 67318 launched, continuing testing... # I am the watchdog process 67318, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 68.7045633792877, n = 100, s/n = 0.687045633792877 # I am the main process 67317, terminating the watchdog process 67318 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 67318 = 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 67320, starting the watchdog process... # The watchdog process 67321 launched, continuing testing... # I am the watchdog process 67321, sleeping for 360 seconds... ok 1 # tick = 1, one = 1515837783, two = 1515837783, three = 1515837783 ok 2 # tick = 3, one = 1515837783, two = 1515837783, three = 1515837783 ok 3 - negative time error # t0 = 1515837783.84654 # ualarm(100000) # t1 = 1515837783.94707 # dt = 0.100538015365601 # r = 1.00538015365601 ok 4 # t0 = 1515837783.94731 # ualarm(1100000) # t1 = 1515837785.04851 # dt = 1.10119795799255 # r = 1.0010890527205 ok 5 # t0 = 1515837785.04876 # ualarm(2200000) # t1 = 1515837787.25176 # dt = 2.20300507545471 # r = 1.00136594338851 ok 6 # t0 = 1515837787.25203 # ualarm(4300000) # t1 = 1515837791.5536 # dt = 4.30156111717224 # r = 1.00036305050517 ok 7 # t0 = 1515837791.55386 # got0 = 0 # t1 = 1515837791.85386 # t1 - t0 = 0.300000190734863 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 67320, terminating the watchdog process 67321 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 67321 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 67322, starting the watchdog process... # The watchdog process 67323 launched, continuing testing... # I am the watchdog process 67323, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 67322, terminating the watchdog process 67323 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 67323 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=96, 27 wallclock secs ( 0.05 usr 0.02 sys + 14.66 cusr 0.14 csys = 14.87 CPU) Result: PASS JHI/Time-HiRes-1.9753.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/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9753-VcYBbR && tar cvf - Time-HiRes-1.9753.ppd blib) | gzip -c >/Users/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9753.tar.gz Time-HiRes-1.9753.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 2018-01-13T02:03:16