PATH=/usr/bin:/bin:/Users/fly2400/cpanfly-5.24/var/megalib/bin Start 2019-02-20T02:01:06 ActivePerl-2400 CPAN-2.10 Reading '/Users/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Tue, 19 Feb 2019 06:55:15 GMT Checksum for /Users/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/A/AT/ATOOMIC/Time-HiRes-1.9760.tar.gz ok Time-HiRes-1.9760/ Time-HiRes-1.9760/HiRes.xs Time-HiRes-1.9760/fallback/ Time-HiRes-1.9760/Changes Time-HiRes-1.9760/HiRes.pm Time-HiRes-1.9760/MANIFEST Time-HiRes-1.9760/ppport.h Time-HiRes-1.9760/t/ Time-HiRes-1.9760/README Time-HiRes-1.9760/TODO Time-HiRes-1.9760/typemap Time-HiRes-1.9760/META.yml Time-HiRes-1.9760/Makefile.PL Time-HiRes-1.9760/hints/ Time-HiRes-1.9760/META.json Time-HiRes-1.9760/hints/dynixptx.pl Time-HiRes-1.9760/hints/sco.pl Time-HiRes-1.9760/hints/svr4.pl Time-HiRes-1.9760/hints/dec_osf.pl Time-HiRes-1.9760/hints/solaris.pl Time-HiRes-1.9760/hints/aix.pl Time-HiRes-1.9760/hints/linux.pl Time-HiRes-1.9760/hints/irix.pl Time-HiRes-1.9760/t/utime.t Time-HiRes-1.9760/t/time.t Time-HiRes-1.9760/t/sleep.t Time-HiRes-1.9760/t/tv_interval.t Time-HiRes-1.9760/t/itimer.t Time-HiRes-1.9760/t/Watchdog.pm Time-HiRes-1.9760/t/alarm.t Time-HiRes-1.9760/t/stat.t Time-HiRes-1.9760/t/nanosleep.t Time-HiRes-1.9760/t/gettimeofday.t Time-HiRes-1.9760/t/usleep.t Time-HiRes-1.9760/t/clock.t Time-HiRes-1.9760/t/ualarm.t Time-HiRes-1.9760/fallback/const-c.inc Time-HiRes-1.9760/fallback/const-xs.inc Configuring A/AT/ATOOMIC/Time-HiRes-1.9760.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'. ATOOMIC/Time-HiRes-1.9760.tar.gz /Users/fly2400/ap2400-300558/bin/perl-dynamic Makefile.PL -- OK Running make for A/AT/ATOOMIC/Time-HiRes-1.9760.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.9760-FjLvr1/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.9760\" -DXS_VERSION=\"1.9760\" "-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 -DUSE_PPPORT_H 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 ATOOMIC/Time-HiRes-1.9760.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 1523, starting the watchdog process... # The watchdog process 1524 launched, continuing testing... # I am the watchdog process 1524, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.302986 # Select returned! 4 0.303054 # 0.898982 # Tick! 3 0.60455 # Select returned! 3 0.604622 # 0.798459333333333 # Tick! 2 0.9079 # Select returned! 2 0.908005 # 0.697331666666667 # Tick! 1 1.211412 # Select returned! 1 1.211461 # 0.596179666666667 # Tick! 0 1.514991 # Select returned! 0 1.515059 # 0.494980333333333 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1550656873.18813, t0 = 1550656873.1881, dt = 3.29017639160156e-05 # N = 2048, t1 = 1550656873.18823, t0 = 1550656873.18816, dt = 6.29425048828125e-05 # N = 4096, t1 = 1550656873.18836, t0 = 1550656873.18823, dt = 0.00013279914855957 # N = 8192, t1 = 1550656873.18865, t0 = 1550656873.18839, dt = 0.000256061553955078 # N = 16384, t1 = 1550656873.18917, t0 = 1550656873.18867, dt = 0.000499963760375977 # N = 32768, t1 = 1550656873.19019, t0 = 1550656873.1892, dt = 0.000985860824584961 # N = 65536, t1 = 1550656873.19217, t0 = 1550656873.19021, dt = 0.00196099281311035 # N = 131072, t1 = 1550656873.19611, t0 = 1550656873.1922, dt = 0.00390481948852539 # N = 262144, t1 = 1550656873.20395, t0 = 1550656873.19613, dt = 0.00781893730163574 # N = 524288, t1 = 1550656873.21973, t0 = 1550656873.20398, dt = 0.0157449245452881 # Alarm 1 - 1550656873.23083 # Alarm 2 - 1550656873.26212 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 1523, terminating the watchdog process 1524 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 1524 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 1525, starting the watchdog process... # The watchdog process 1526 launched, continuing testing... # I am the watchdog process 1526, 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 = 1550656879.04541, t0 = 1550656877.53876 # dt = 1.50664806365967, rt = 0.00443204243977857 ok 2 ok 3 ok 4 # CLOCKS_PER_SEC = 1000000 # delay = 1000000 # clock = 0.092335 # clock = 0.092335 0.122195 # clock = 0.092335 0.122195 0.152156 # clock = 0.092335 0.122195 0.152156 0.182163 ok 5 # I am the main process 1525, terminating the watchdog process 1526 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 1526 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 1527, starting the watchdog process... # The watchdog process 1528 launched, continuing testing... # I am the watchdog process 1528, 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 1527, terminating the watchdog process 1528 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 1528 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 1529, starting the watchdog process... # The watchdog process 1530 launched, continuing testing... # I am the watchdog process 1530, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.49973 0.4 # Tick! 2 0.519576 # Tick! 1 0.934442 # Tick! 0 1.34911 # Tick! 0 1.764095 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 1529, terminating the watchdog process 1530 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 1530 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 1531, starting the watchdog process... # The watchdog process 1532 launched, continuing testing... # I am the watchdog process 1532, 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 1531, terminating the watchdog process 1532 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 1532 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 1533, starting the watchdog process... # The watchdog process 1534 launched, continuing testing... # I am the watchdog process 1534, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1550656884.32576 ok 3 # sleep...0.501722 ok 4 # I am the main process 1533, terminating the watchdog process 1534 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 1534 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 1535, starting the watchdog process... # The watchdog process 1536 launched, continuing testing... # I am the watchdog process 1536, 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 = 1550656885 1550656885 1550656885 1550656885 1550656886 # atime = 1550656885 1550656885 1550656885 1550656886 1550656886 # 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 1535, terminating the watchdog process 1536 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 1536 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 1537, starting the watchdog process... # The watchdog process 1538 launched, continuing testing... # I am the watchdog process 1538, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 53.5391652584076, n = 100, s/n = 0.535391652584076 # I am the main process 1537, terminating the watchdog process 1538 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 1538 = 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 1540, starting the watchdog process... # The watchdog process 1541 launched, continuing testing... # I am the watchdog process 1541, sleeping for 360 seconds... ok 1 # tick = 1, one = 1550656886, two = 1550656886, three = 1550656886 ok 2 # tick = 3, one = 1550656886, two = 1550656886, three = 1550656886 ok 3 - negative time error # t0 = 1550656886.78634 # ualarm(100000) # t1 = 1550656886.88949 # dt = 0.103146076202393 # r = 1.03146076202393 ok 4 # t0 = 1550656886.8898 # ualarm(1100000) # t1 = 1550656887.99298 # dt = 1.1031858921051 # r = 1.00289626555009 ok 5 # t0 = 1550656887.99329 # ualarm(2200000) # t1 = 1550656890.19656 # dt = 2.20327281951904 # r = 1.00148764523593 ok 6 # t0 = 1550656890.19688 # ualarm(4300000) # t1 = 1550656894.49841 # dt = 4.3015341758728 # r = 1.0003567850867 ok 7 # t0 = 1550656894.4987 # got0 = 0 # t1 = 1550656894.79871 # t1 - t0 = 0.300000190734863 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 1540, terminating the watchdog process 1541 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 1541 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 1542, starting the watchdog process... # The watchdog process 1543 launched, continuing testing... # I am the watchdog process 1543, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 1542, terminating the watchdog process 1543 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 1543 = 1 # All done. ok t/utime.t ......... skipped: no hires_utime All tests successful. Files=12, Tests=96, 28 wallclock secs ( 0.05 usr 0.02 sys + 15.12 cusr 0.19 csys = 15.38 CPU) Result: PASS ATOOMIC/Time-HiRes-1.9760.tar.gz make test TEST_VERBOSE=1 -- OK Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/A/AT/ATOOMIC/CHECKSUMS Nicolas R. <atoomic@cpan.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9760-FjLvr1 && tar cvf - Time-HiRes-1.9760.ppd blib) | gzip -c >/Users/fly2400/cpanfly-5.24/var/REPO/A/AT/ATOOMIC/Time-HiRes-1.9760.tar.gz Time-HiRes-1.9760.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 2019-02-20T02:01:39