PATH=/usr/bin:/bin:/Users/fly2000/cpanfly-5.20/var/megalib/bin Start 2016-11-21T10:01:56 ActivePerl-2000 CPAN-2.10 Reading '/Users/fly2000/cpanfly-5.20/var/cpan/Metadata' Database was generated on Mon, 21 Nov 2016 06:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9741.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.9741.tar.gz ok Time-HiRes-1.9741/ Time-HiRes-1.9741/Changes Time-HiRes-1.9741/fallback/ Time-HiRes-1.9741/hints/ Time-HiRes-1.9741/HiRes.pm Time-HiRes-1.9741/HiRes.xs Time-HiRes-1.9741/Makefile.PL Time-HiRes-1.9741/MANIFEST Time-HiRes-1.9741/META.json Time-HiRes-1.9741/META.yml Time-HiRes-1.9741/ppport.h Time-HiRes-1.9741/README Time-HiRes-1.9741/t/ Time-HiRes-1.9741/TODO Time-HiRes-1.9741/typemap Time-HiRes-1.9741/t/alarm.t Time-HiRes-1.9741/t/clock.t Time-HiRes-1.9741/t/gettimeofday.t Time-HiRes-1.9741/t/itimer.t Time-HiRes-1.9741/t/nanosleep.t Time-HiRes-1.9741/t/sleep.t Time-HiRes-1.9741/t/stat.t Time-HiRes-1.9741/t/time.t Time-HiRes-1.9741/t/tv_interval.t Time-HiRes-1.9741/t/ualarm.t Time-HiRes-1.9741/t/usleep.t Time-HiRes-1.9741/t/utime.t Time-HiRes-1.9741/t/Watchdog.pm Time-HiRes-1.9741/hints/aix.pl Time-HiRes-1.9741/hints/dec_osf.pl Time-HiRes-1.9741/hints/dynixptx.pl Time-HiRes-1.9741/hints/irix.pl Time-HiRes-1.9741/hints/linux.pl Time-HiRes-1.9741/hints/sco.pl Time-HiRes-1.9741/hints/solaris.pl Time-HiRes-1.9741/hints/svr4.pl Time-HiRes-1.9741/fallback/const-c.inc Time-HiRes-1.9741/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9741.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 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 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.9741.tar.gz /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9741.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.9741\" -DXS_VERSION=\"1.9741\" "-I/Users/fly2000/ap2000-298465/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 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.9741.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 48031, starting the watchdog process... # The watchdog process 48032 launched, continuing testing... # I am the watchdog process 48032, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.30964 # Select returned! 4 0.309712 # 0.896762666666667 # Tick! 3 0.611873 # Select returned! 3 0.611924 # 0.796025333333333 # Tick! 2 0.912032 # Select returned! 2 0.912082 # 0.695972666666667 # Tick! 1 1.212149 # Select returned! 1 1.212195 # 0.595935 # Tick! 0 1.518359 # Select returned! 0 1.518418 # 0.493860666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1479751333.44921, t0 = 1479751333.44912, dt = 9.10758972167969e-05 # N = 2048, t1 = 1479751333.44942, t0 = 1479751333.44924, dt = 0.000179052352905273 # N = 4096, t1 = 1479751333.44978, t0 = 1479751333.44943, dt = 0.000346899032592773 # N = 8192, t1 = 1479751333.45048, t0 = 1479751333.44979, dt = 0.000694036483764648 # N = 16384, t1 = 1479751333.45187, t0 = 1479751333.45049, dt = 0.00138306617736816 # N = 32768, t1 = 1479751333.45504, t0 = 1479751333.45188, dt = 0.00315093994140625 # N = 65536, t1 = 1479751333.46072, t0 = 1479751333.45506, dt = 0.00565195083618164 # N = 131072, t1 = 1479751333.47223, t0 = 1479751333.46075, dt = 0.0114820003509521 # Alarm 1 - 1479751333.48239 # Alarm 2 - 1479751333.50831 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 48031, terminating the watchdog process 48032 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 48032 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 48033, starting the watchdog process... # I am the watchdog process 48034, sleeping for 360 seconds... # The watchdog process 48034 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 = 1479751339.35343, t0 = 1479751337.85338 # dt = 1.50005149841309, rt = 3.4332275390625e-05 ok 2 ok 3 ok 4 # clock = 0.046141 # clock = 0.046141 0.11074 # clock = 0.046141 0.11074 0.175268 # clock = 0.046141 0.11074 0.175268 0.239524 ok 5 # I am the main process 48033, terminating the watchdog process 48034 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 48034 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 48035, starting the watchdog process... # I am the watchdog process 48036, sleeping for 360 seconds... # The watchdog process 48036 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 48035, terminating the watchdog process 48036 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 48036 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 48037, starting the watchdog process... # The watchdog process 48038 launched, continuing testing... # I am the watchdog process 48038, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.497545 0.4 # Tick! 2 1.175626 # Tick! 1 2.032092 # Tick! 0 2.886614 # Tick! 0 3.735747 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 48037, terminating the watchdog process 48038 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 48038 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 48039, starting the watchdog process... # The watchdog process 48040 launched, continuing testing... # I am the watchdog process 48040, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 48039, terminating the watchdog process 48040 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 48040 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 48041, starting the watchdog process... # The watchdog process 48042 launched, continuing testing... # I am the watchdog process 48042, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1479751346.83955 ok 3 # sleep...0.500004 ok 4 # I am the main process 48041, terminating the watchdog process 48042 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 48042 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 48043, starting the watchdog process... # I am the watchdog process 48044, sleeping for 360 seconds... # The watchdog process 48044 launched, continuing testing... 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 = 1479751347 1479751347 1479751348 1479751348 1479751348 # atime = 1479751347 1479751348 1479751348 1479751348 1479751349 # 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 48043, terminating the watchdog process 48044 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 48044 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 48045, starting the watchdog process... # The watchdog process 48046 launched, continuing testing... # I am the watchdog process 48046, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 9.01770114898682, n = 100, s/n = 0.0901770114898682 # I am the main process 48045, terminating the watchdog process 48046 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 48046 = 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 48048, starting the watchdog process... # The watchdog process 48049 launched, continuing testing... # I am the watchdog process 48049, sleeping for 360 seconds... ok 1 # tick = 1, one = 1479751349, two = 1479751349, three = 1479751349 ok 2 # tick = 3, one = 1479751349, two = 1479751349, three = 1479751349 ok 3 - negative time error # t0 = 1479751349.39659 # ualarm(100000) # t1 = 1479751349.49696 # dt = 0.100371122360229 # r = 1.00371122360229 ok 4 # t0 = 1479751349.49713 # ualarm(1100000) # t1 = 1479751350.59716 # dt = 1.10002493858337 # r = 1.00002267143943 ok 5 # t0 = 1479751350.59739 # ualarm(2200000) # t1 = 1479751352.79748 # dt = 2.20009207725525 # r = 1.00004185329784 ok 6 # t0 = 1479751352.79777 # ualarm(4300000) # t1 = 1479751357.10365 # dt = 4.3058750629425 # r = 1.00136629370756 ok 7 # t0 = 1479751357.10393 # got0 = 0 # t1 = 1479751357.41344 # t1 - t0 = 0.309510946273804 # got1 = 190474 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 48048, terminating the watchdog process 48049 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 48049 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 48050, starting the watchdog process... # The watchdog process 48053 launched, continuing testing... # I am the watchdog process 48053, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 48050, terminating the watchdog process 48053 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 48053 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 31 wallclock secs ( 0.08 usr 0.04 sys + 8.76 cusr 0.23 csys = 9.11 CPU) Result: PASS JHI/Time-HiRes-1.9741.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2000/cpanfly-5.20/var/tmp/cpan_build/Time-HiRes-1.9741-A76QlN && tar cvf - Time-HiRes-1.9741.ppd blib) | gzip -c >/Users/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI/Time-HiRes-1.9741.tar.gz Time-HiRes-1.9741.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-11-21T10:02:42