PATH=/Users/fly1600/bin:/Users/fly1600/ap1600/bin:/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/bin:/usr/X11/bin Start 2012-06-05T14:52:22 ActivePerl-1600 CPAN-1.9402 Going to read '/Users/fly1600/var/cpan/Metadata' Database was generated on Tue, 05 Jun 2012 21:31:03 GMT ______________________ D i s t r o P r e f s ______________________ Time-HiRes.yml[0] Running make for Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Checksum for /net/nas/data/cpan/authors/id/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz ok Time-HiRes-1.9725/ Time-HiRes-1.9725/META.yml Time-HiRes-1.9725/MANIFEST Time-HiRes-1.9725/HiRes.pm Time-HiRes-1.9725/Changes Time-HiRes-1.9725/ppport.h Time-HiRes-1.9725/README Time-HiRes-1.9725/hints/ Time-HiRes-1.9725/hints/aix.pl Time-HiRes-1.9725/hints/dynixptx.pl Time-HiRes-1.9725/hints/solaris.pl Time-HiRes-1.9725/hints/linux.pl Time-HiRes-1.9725/hints/irix.pl Time-HiRes-1.9725/hints/dec_osf.pl Time-HiRes-1.9725/hints/svr4.pl Time-HiRes-1.9725/hints/sco.pl Time-HiRes-1.9725/TODO Time-HiRes-1.9725/HiRes.xs Time-HiRes-1.9725/fallback/ Time-HiRes-1.9725/fallback/const-c.inc Time-HiRes-1.9725/fallback/const-xs.inc Time-HiRes-1.9725/typemap Time-HiRes-1.9725/Makefile.PL Time-HiRes-1.9725/META.json Time-HiRes-1.9725/t/ Time-HiRes-1.9725/t/Watchdog.pm Time-HiRes-1.9725/t/sleep.t Time-HiRes-1.9725/t/nanosleep.t Time-HiRes-1.9725/t/itimer.t Time-HiRes-1.9725/t/usleep.t Time-HiRes-1.9725/t/ualarm.t Time-HiRes-1.9725/t/clock.t Time-HiRes-1.9725/t/gettimeofday.t Time-HiRes-1.9725/t/time.t Time-HiRes-1.9725/t/tv_interval.t Time-HiRes-1.9725/t/stat.t Time-HiRes-1.9725/t/alarm.t Going to apply 1 patch: /Users/fly1600/etc/distroprefs/Time-HiRes-1.9719.patch /usr/bin/patch -N --fuzz=3 -p1 <= IV_1E6 || uinterval >= IV_1E6) - croak("Time::HiRes::ualarm(%d, %d): useconds or uinterval equal to or more than %"IVdf, useconds, uinterval, IV_1E6); RETVAL = ualarm(useconds, uinterval); #endif @@ -948,8 +946,6 @@ } } #else - if (useconds >= IV_1E6 || uinterval >= IV_1E6) - croak("Time::HiRes::alarm(%d, %d): seconds or interval equal to or more than 1.0 ", useconds, uinterval, IV_1E6); RETVAL = (NV)ualarm( useconds, uinterval ) / NV_1E6; #endif } EOT patching file HiRes.xs Hunk #1 succeeded at 925 (offset 5 lines). Hunk #2 succeeded at 953 (offset 7 lines). CPAN.pm: Going to build Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz >>> /Users/fly1600/ap1600/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()... NOT found. Looking for clock_getres()... NOT found. Looking for clock_nanosleep()... NOT found. Looking for clock()... 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 Writing Makefile for Time::HiRes Writing MYMETA.yml and MYMETA.json Now you may issue 'make'. Do not forget also 'make test'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm /Users/fly1600/ap1600/bin/perl-dynamic /Users/fly1600/ap1600/lib/ExtUtils/xsubpp -typemap /Users/fly1600/ap1600/lib/ExtUtils/typemap -typemap typemap HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -fno-common -DPERL_DARWIN -no-cpp-precomp -arch i386 -arch x86_64 -nostdinc -B/Developer/SDKs/MacOSX10.6.sdk/usr/include/gcc -B/Developer/SDKs/MacOSX10.6.sdk/usr/lib/gcc -isystem/Developer/SDKs/MacOSX10.6.sdk/usr/include -F/Developer/SDKs/MacOSX10.6.sdk/System/Library/Frameworks -mmacosx-version-min=10.6 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9725\" -DXS_VERSION=\"1.9725\" "-I/Users/fly1600/ap1600/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT=1 -DATLEASTFIVEOHOHFIVE HiRes.c Running Mkbootstrap for Time::HiRes () chmod 644 HiRes.bs rm -f blib/arch/auto/Time/HiRes/HiRes.bundle cc -mmacosx-version-min=10.5 -bundle -undefined dynamic_lookup -Wl,-search_paths_first -arch i386 -arch x86_64 -Wl,-syslibroot,/Developer/SDKs/MacOSX10.6.sdk -mmacosx-version-min=10.6 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.bundle \ \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.bundle cp HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs chmod 644 blib/arch/auto/Time/HiRes/HiRes.bs Manifying blib/man3/Time::HiRes.3 ZEFRAM/Time-HiRes-1.9725.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 /Users/fly1600/ap1600/bin/perl-dynamic "-MExtUtils::Command::MM" "-e" "test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/alarm.t ......... 1..10 # I am the main process 67256, starting the watchdog process... # The watchdog process 67257 launched, continuing testing... # I am the watchdog process 67257, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300365 # Select returned! 4 0.300512 # 0.899829333333333 # Tick! 3 0.600823 # Select returned! 3 0.600999 # 0.799667 # Tick! 2 0.901398 # Select returned! 2 0.901572 # 0.699476 # Tick! 1 1.201931 # Select returned! 1 1.202106 # 0.599298 # Tick! 0 1.50249 # Select returned! 0 1.502671 # 0.499109666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1338933152.80589, t0 = 1338933152.8058, dt = 8.60691070556641e-05 # N = 2048, t1 = 1338933152.80612, t0 = 1338933152.80595, dt = 0.0001678466796875 # N = 4096, t1 = 1338933152.8065, t0 = 1338933152.80617, dt = 0.000338077545166016 # N = 8192, t1 = 1338933152.80716, t0 = 1338933152.80655, dt = 0.000612020492553711 # N = 16384, t1 = 1338933152.80836, t0 = 1338933152.8072, dt = 0.00116395950317383 # N = 32768, t1 = 1338933152.81073, t0 = 1338933152.8084, dt = 0.00232696533203125 # N = 65536, t1 = 1338933152.8153, t0 = 1338933152.81077, dt = 0.00453495979309082 # N = 131072, t1 = 1338933152.82361, t0 = 1338933152.81534, dt = 0.00827193260192871 # N = 262144, t1 = 1338933152.8402, t0 = 1338933152.82364, dt = 0.0165610313415527 # Alarm 1 - 1338933152.85032 # Alarm 2 - 1338933152.88413 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 67256, terminating the watchdog process 67257 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 67257 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 67258, starting the watchdog process... # The watchdog process 67259 launched, continuing testing... # I am the watchdog process 67259, sleeping for 360 seconds... ok 1 - require Time::HiRes; # have_clock_gettime = 0 # have_clock_getres = 0 # have_clock_nanosleep = 0 # have_clock = 1 ok 2 # skip no clock_gettime ok 3 # skip no clock_getres ok 4 # skip no clock_nanosleep # clock = 0.040732 # clock = 0.040732 0.103638 # clock = 0.040732 0.103638 0.166792 # clock = 0.040732 0.103638 0.166792 0.229714 ok 5 # I am the main process 67258, terminating the watchdog process 67259 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 67259 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 67260, starting the watchdog process... # The watchdog process 67261 launched, continuing testing... # I am the watchdog process 67261, 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 67260, terminating the watchdog process 67261 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67261 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 67262, starting the watchdog process... # The watchdog process 67263 launched, continuing testing... # I am the watchdog process 67263, sleeping for 360 seconds... # setitimer: 0 0 ok 1 # getitimer: 0.497888 0.4 # Tick! 2 0.507582 # Tick! 1 0.913343 # Tick! 0 1.31929 # Tick! 0 1.725341 # getitimer: 0 0 ok 2 # I am the main process 67262, terminating the watchdog process 67263 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 67263 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 67264, starting the watchdog process... # The watchdog process 67265 launched, continuing testing... # I am the watchdog process 67265, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 67264, terminating the watchdog process 67265 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 67265 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 67266, starting the watchdog process... # The watchdog process 67267 launched, continuing testing... # I am the watchdog process 67267, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1338933160.7487 ok 3 # sleep...0.500149 ok 4 # I am the main process 67266, terminating the watchdog process 67267 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67267 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 67268, starting the watchdog process... # The watchdog process 67269 launched, continuing testing... # I am the watchdog process 67269, 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 # mtime = 1338933161 1338933161 1338933162 1338933162 1338933162 # atime = 1338933161 1338933161 1338933162 1338933162 1338933162 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 67268, terminating the watchdog process 67269 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 67269 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 67270, starting the watchdog process... # The watchdog process 67271 launched, continuing testing... # I am the watchdog process 67271, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 85.9907200336456, n = 100, s/n = 0.859907200336456 # I am the main process 67270, terminating the watchdog process 67271 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 67271 = 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 67273, starting the watchdog process... # The watchdog process 67274 launched, continuing testing... # I am the watchdog process 67274, sleeping for 360 seconds... ok 1 # tick = 1, one = 1338933162, two = 1338933162, three = 1338933162 ok 2 # tick = 3, one = 1338933162, two = 1338933162, three = 1338933162 ok 3 - negative time error # t0 = 1338933162.99941 # ualarm(100000) # t1 = 1338933163.09953 # dt = 0.100123882293701 # r = 1.00123882293701 ok 4 # t0 = 1338933163.09978 # ualarm(1100000) # t1 = 1338933164.19987 # dt = 1.10008120536804 # r = 1.00007382306186 ok 5 # t0 = 1338933164.20015 # ualarm(2200000) # t1 = 1338933166.40018 # dt = 2.20002794265747 # r = 1.00001270120794 ok 6 # t0 = 1338933166.40047 # ualarm(4300000) # t1 = 1338933170.70048 # dt = 4.30001401901245 # r = 1.00000326023545 ok 7 # t0 = 1338933170.70081 # got0 = 0 # t1 = 1338933171.00082 # t1 - t0 = 0.30000114440918 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 67273, terminating the watchdog process 67274 before it terminates me in 351 seconds (testing took 9 seconds). # kill KILL 67274 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 67275, starting the watchdog process... # The watchdog process 67276 launched, continuing testing... # I am the watchdog process 67276, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 67275, terminating the watchdog process 67276 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 67276 = 1 # All done. ok All tests successful. Files=11, Tests=68, 24 wallclock secs ( 0.08 usr 0.04 sys + 14.58 cusr 0.14 csys = 14.84 CPU) Result: PASS ZEFRAM/Time-HiRes-1.9725.tar.gz make test TEST_VERBOSE=1 -- OK Andrew Main (Zefram) <zefram@fysh.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /Users/fly1600/var/cpan/build/Time-HiRes-1.9725-RoYmsg && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/Users/fly1600/var/REPO/Z/ZE/ZEFRAM/Time-HiRes-1.9725.tar.gz Time-HiRes-1.9725.ppd blib/ blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bundle blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/man3/ blib/man3/Time::HiRes.3 >>> mv /Users/fly1600/var/cpan/build/Time-HiRes-1.9725-RoYmsg/Time-HiRes-1.9725.ppd /Users/fly1600/var/REPO/Z/ZE/ZEFRAM Can't log to '/Users/fly1600/var/tmp/Library/Logs/ppm4.log': No such file or directory at /Users/fly1600/lib/ActivePerl/PPM/Logger.pm line 95. [InstallArea.pm:339] Created /Users/fly1600/var/megalib/etc/ppm-megalib-dirty [InstallArea.pm:362] Installing Time-HiRes with id 64 [InstallArea.pm:369] Copy /tmp/ppm-3zA5PJ/Time-HiRes-1.9725/blib/arch --> /Users/fly1600/var/megalib [InstallArea.pm:548] /Users/fly1600/var/megalib/auto/Time/HiRes/HiRes.bs written [InstallArea.pm:548] /Users/fly1600/var/megalib/auto/Time/HiRes/HiRes.bundle written [InstallArea.pm:369] Copy /tmp/ppm-3zA5PJ/Time-HiRes-1.9725/blib/html --> /Users/fly1600/var/megalib/html [InstallArea.pm:548] /Users/fly1600/var/megalib/html/site/lib/Time/HiRes.html written [InstallArea.pm:369] Copy /tmp/ppm-3zA5PJ/Time-HiRes-1.9725/blib/lib --> /Users/fly1600/var/megalib [InstallArea.pm:548] /Users/fly1600/var/megalib/Time/HiRes.pm written [InstallArea.pm:418] Commit install [InstallArea.pm:454] unlink /Users/fly1600/var/megalib/etc/ppm-megalib-dirty Finished 2012-06-05T14:52:56