PATH=/Users/fly2200/cpanfly-5.22/bin:/Users/fly2200/ap2200-299195/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/bin:/opt/X11/bin:/Users/fly2200/cpanfly-5.22/var/megalib/bin Start 2017-01-05T06:42:36 ActivePerl-2200 CPAN-2.10 Reading '/Users/fly2200/cpanfly-5.22/var/cpan/Metadata' Database was generated on Thu, 05 Jan 2017 06:29:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9741.tar.gz Can't chown '/Users/fly2200/cpanfly-5.22/var/cpan/FTPstats.yml.26952' to fly2400/cpanflies: Operation not permitted Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /Users/fly2200/cpanfly-5.22/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/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 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/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.9741.tar.gz /Users/fly2200/ap2200-299195/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/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.9741\" -DXS_VERSION=\"1.9741\" "-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.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/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 27522, starting the watchdog process... # I am the watchdog process 27523, sleeping for 360 seconds... # The watchdog process 27523 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.305403 # Select returned! 4 0.305481 # 0.898173 # Tick! 3 0.605594 # Select returned! 3 0.605647 # 0.798117666666667 # Tick! 2 0.907727 # Select returned! 2 0.907822 # 0.697392666666667 # Tick! 1 1.209968 # Select returned! 1 1.210015 # 0.596661666666667 # Tick! 0 1.512171 # Select returned! 0 1.512216 # 0.495928 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1483627364.75352, t0 = 1483627364.75347, dt = 4.79221343994141e-05 # N = 2048, t1 = 1483627364.75365, t0 = 1483627364.75355, dt = 9.89437103271484e-05 # N = 4096, t1 = 1483627364.75386, t0 = 1483627364.75366, dt = 0.000199079513549805 # N = 8192, t1 = 1483627364.75429, t0 = 1483627364.75387, dt = 0.000419139862060547 # N = 16384, t1 = 1483627364.75516, t0 = 1483627364.7543, dt = 0.000857114791870117 # N = 32768, t1 = 1483627364.75693, t0 = 1483627364.75517, dt = 0.00175309181213379 # N = 65536, t1 = 1483627364.76027, t0 = 1483627364.75694, dt = 0.0033259391784668 # N = 131072, t1 = 1483627364.76672, t0 = 1483627364.76028, dt = 0.00643396377563477 # N = 262144, t1 = 1483627364.77554, t0 = 1483627364.76675, dt = 0.00879096984863281 # N = 524288, t1 = 1483627364.79289, t0 = 1483627364.77557, dt = 0.017319917678833 # Alarm 1 - 1483627364.80378 # Alarm 2 - 1483627364.87823 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 27522, terminating the watchdog process 27523 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 27523 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 28027, starting the watchdog process... # The watchdog process 28028 launched, continuing testing... # I am the watchdog process 28028, 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 = 1483627370.74201, t0 = 1483627369.23807 # dt = 1.5039370059967, rt = 0.00262467066446948 ok 2 ok 3 ok 4 # clock = 0.025442 # clock = 0.025442 0.067752 # clock = 0.025442 0.067752 0.117676 # clock = 0.025442 0.067752 0.117676 0.167792 ok 5 # I am the main process 28027, terminating the watchdog process 28028 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 28028 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 28154, starting the watchdog process... # I am the watchdog process 28167, sleeping for 360 seconds... # The watchdog process 28167 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 28154, terminating the watchdog process 28167 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 28167 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 28195, starting the watchdog process... # I am the watchdog process 28196, sleeping for 360 seconds... # The watchdog process 28196 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.498097 0.4 # Tick! 2 0.899095 # Tick! 1 1.658374 # Tick! 0 2.260649 # Tick! 0 2.88841 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 28195, terminating the watchdog process 28196 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 28196 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 28268, starting the watchdog process... # The watchdog process 28269 launched, continuing testing... # I am the watchdog process 28269, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 28268, terminating the watchdog process 28269 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 28269 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 28270, starting the watchdog process... # I am the watchdog process 28271, sleeping for 360 seconds... # The watchdog process 28271 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1483627377.04036 ok 3 # sleep...0.50201 ok 4 # I am the main process 28270, terminating the watchdog process 28271 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 28271 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 28273, starting the watchdog process... # The watchdog process 28274 launched, continuing testing... # I am the watchdog process 28274, 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 = 1483627377 1483627378 1483627378 1483627378 1483627379 # atime = 1483627377 1483627378 1483627378 1483627378 1483627379 # 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 28273, terminating the watchdog process 28274 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 28274 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 28283, starting the watchdog process... # I am the watchdog process 28284, sleeping for 360 seconds... # The watchdog process 28284 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 23.7545075416565, n = 100, s/n = 0.237545075416565 # I am the main process 28283, terminating the watchdog process 28284 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 28284 = 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 28286, starting the watchdog process... # The watchdog process 28287 launched, continuing testing... # I am the watchdog process 28287, sleeping for 360 seconds... ok 1 # tick = 1, one = 1483627379, two = 1483627379, three = 1483627379 ok 2 # tick = 3, one = 1483627379, two = 1483627379, three = 1483627379 ok 3 - negative time error # t0 = 1483627379.35155 # ualarm(100000) # t1 = 1483627379.4526 # dt = 0.101053953170776 # r = 1.01053953170776 ok 4 # t0 = 1483627379.45282 # ualarm(1100000) # t1 = 1483627380.55536 # dt = 1.10254001617432 # r = 1.00230910561301 ok 5 # t0 = 1483627380.56036 # ualarm(2200000) # t1 = 1483627382.76266 # dt = 2.20229983329773 # r = 1.0010453787717 ok 6 # t0 = 1483627382.76363 # ualarm(4300000) # t1 = 1483627387.06484 # dt = 4.30120301246643 # r = 1.00027977034103 ok 7 # t0 = 1483627387.06561 # got0 = 0 # t1 = 1483627387.36561 # t1 - t0 = 0.300000905990601 # got1 = 199998 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 28286, terminating the watchdog process 28287 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 28287 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 28818, starting the watchdog process... # I am the watchdog process 28819, sleeping for 360 seconds... # The watchdog process 28819 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 28818, terminating the watchdog process 28819 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 28819 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=95, 28 wallclock secs ( 0.07 usr 0.02 sys + 10.86 cusr 0.17 csys = 11.12 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/fly2200/cpanfly-5.22/var/tmp/cpan_build/Time-HiRes-1.9741-qd6pWY && tar cvf - Time-HiRes-1.9741.ppd blib) | gzip -c >/Users/fly2200/cpanfly-5.22/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 2017-01-05T06:43:11