PATH=/usr/bin:/bin:/Users/fly2400/cpanfly-5.24/var/megalib/bin Start 2016-10-21T15:09:06 ActivePerl-2400 CPAN-2.10 Reading '/Users/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Fri, 21 Oct 2016 05:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9740.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.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/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 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/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.9740.tar.gz /Users/fly2400/ap2400-300558/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 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.9740-mYo7hn/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.9740\" -DXS_VERSION=\"1.9740\" "-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.9740.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 7553, starting the watchdog process... # I am the watchdog process 7554, sleeping for 360 seconds... # The watchdog process 7554 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.302203 # Select returned! 4 0.302277 # 0.899241 # Tick! 3 0.604504 # Select returned! 3 0.604562 # 0.798479333333333 # Tick! 2 0.906835 # Select returned! 2 0.906938 # 0.697687333333333 # Tick! 1 1.210594 # Select returned! 1 1.21066 # 0.596446666666667 # Tick! 0 1.512022 # Select returned! 0 1.512076 # 0.495974666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1477087755.01718, t0 = 1477087755.01711, dt = 6.69956207275391e-05 # N = 2048, t1 = 1477087755.01737, t0 = 1477087755.01723, dt = 0.000135898590087891 # N = 4096, t1 = 1477087755.01765, t0 = 1477087755.01738, dt = 0.00027012825012207 # N = 8192, t1 = 1477087755.01819, t0 = 1477087755.01766, dt = 0.00053095817565918 # N = 16384, t1 = 1477087755.01924, t0 = 1477087755.0182, dt = 0.00103902816772461 # N = 32768, t1 = 1477087755.0214, t0 = 1477087755.01925, dt = 0.00214910507202148 # N = 65536, t1 = 1477087755.02576, t0 = 1477087755.02142, dt = 0.00433588027954102 # N = 131072, t1 = 1477087755.03509, t0 = 1477087755.0258, dt = 0.00928521156311035 # N = 262144, t1 = 1477087755.04838, t0 = 1477087755.03511, dt = 0.0132639408111572 # Alarm 1 - 1477087755.06071 # Alarm 2 - 1477087755.08468 # Alarm 3 - 1477087755.10569 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 7553, terminating the watchdog process 7554 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 7554 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 7555, starting the watchdog process... # The watchdog process 7556 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 # I am the watchdog process 7556, sleeping for 360 seconds... # t1 = 1477087760.83287, t0 = 1477087759.33064 # dt = 1.50223088264465, rt = 0.00148725509643555 ok 2 ok 3 ok 4 # clock = 0.063625 # clock = 0.063625 0.100504 # clock = 0.063625 0.100504 0.137767 # clock = 0.063625 0.100504 0.137767 0.175076 ok 5 # I am the main process 7555, terminating the watchdog process 7556 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 7556 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 7559, starting the watchdog process... # I am the watchdog process 7560, sleeping for 360 seconds... # The watchdog process 7560 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 7559, terminating the watchdog process 7560 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7560 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 7562, starting the watchdog process... # I am the watchdog process 7563, sleeping for 360 seconds... # The watchdog process 7563 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.499608 0.4 # Tick! 2 0.530319 # Tick! 1 0.945109 # Tick! 0 1.360839 # Tick! 0 1.784571 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 7562, terminating the watchdog process 7563 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 7563 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 7564, starting the watchdog process... # I am the watchdog process 7565, sleeping for 360 seconds... # The watchdog process 7565 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 7564, terminating the watchdog process 7565 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7565 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 7566, starting the watchdog process... # The watchdog process 7567 launched, continuing testing... # I am the watchdog process 7567, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1477087766.10042 ok 3 # sleep...0.502309 ok 4 # I am the main process 7566, terminating the watchdog process 7567 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7567 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 7568, starting the watchdog process... # The watchdog process 7569 launched, continuing testing... # I am the watchdog process 7569, 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 = 1477087766 1477087767 1477087767 1477087767 1477087768 # atime = 1477087766 1477087767 1477087767 1477087767 1477087768 # 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 7568, terminating the watchdog process 7569 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 7569 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 7570, starting the watchdog process... # I am the watchdog process 7571, sleeping for 360 seconds... # The watchdog process 7571 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 24.2464282512665, n = 100, s/n = 0.242464282512665 # I am the main process 7570, terminating the watchdog process 7571 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7571 = 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 7573, starting the watchdog process... # The watchdog process 7574 launched, continuing testing... # I am the watchdog process 7574, sleeping for 360 seconds... ok 1 # tick = 1, one = 1477087768, two = 1477087768, three = 1477087768 ok 2 # tick = 3, one = 1477087768, two = 1477087768, three = 1477087768 ok 3 - negative time error # t0 = 1477087768.46196 # ualarm(100000) # t1 = 1477087768.56331 # dt = 0.101349115371704 # r = 1.01349115371704 ok 4 # t0 = 1477087768.56358 # ualarm(1100000) # t1 = 1477087769.66387 # dt = 1.10029292106628 # r = 1.00026629187844 ok 5 # t0 = 1477087769.66475 # ualarm(2200000) # t1 = 1477087771.8651 # dt = 2.20035314559937 # r = 1.00016052072698 ok 6 # t0 = 1477087771.86587 # ualarm(4300000) # t1 = 1477087776.16773 # dt = 4.30185985565186 # r = 1.0004325245702 ok 7 # t0 = 1477087776.16849 # got0 = 0 # t1 = 1477087776.46849 # t1 - t0 = 0.300000905990601 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 7573, terminating the watchdog process 7574 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 7574 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 7772, starting the watchdog process... # I am the watchdog process 7774, sleeping for 360 seconds... # The watchdog process 7774 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 7772, terminating the watchdog process 7774 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 7774 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 27 wallclock secs ( 0.07 usr 0.02 sys + 14.04 cusr 0.42 csys = 14.55 CPU) Result: PASS JHI/Time-HiRes-1.9740.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.9740-mYo7hn && tar cvf - Time-HiRes-1.9740.ppd blib) | gzip -c >/Users/fly2400/cpanfly-5.24/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-21T15:09:40