PATH=/Users/fly1400/bin:/Users/fly1400/ap1400/bin:/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/bin:/usr/X11/bin Start 2012-02-01T16:48:30 ActivePerl-1400 CPAN-1.9402 Going to read '/Users/fly1400/var/cpan/Metadata' Database was generated on Thu, 02 Feb 2012 00:11:56 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/fly1400/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/fly1400/ap1400/bin/perl 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/fly1400/ap1400/bin/perl /Users/fly1400/var/megalib/ExtUtils/xsubpp -typemap /Users/fly1400/ap1400/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.5.sdk/usr/include/gcc -B/Developer/SDKs/MacOSX10.5.sdk/usr/lib/gcc -isystem/Developer/SDKs/MacOSX10.5.sdk/usr/include -F/Developer/SDKs/MacOSX10.5.sdk/System/Library/Frameworks -mmacosx-version-min=10.5 -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -fstack-protector -O3 -DVERSION=\"1.9725\" -DXS_VERSION=\"1.9725\" "-I/Users/fly1400/ap1400/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.5.sdk -mmacosx-version-min=10.5 -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/fly1400/ap1400/bin/perl "-MExtUtils::Command::MM" "-e" "test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/alarm.t ......... 1..10 # I am the main process 22751, starting the watchdog process... # The watchdog process 22752 launched, continuing testing... # I am the watchdog process 22752, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300672 # Select returned! 4 0.300866 # 0.899711333333333 # Tick! 3 0.601237 # Select returned! 3 0.601414 # 0.799528666666667 # Tick! 2 0.901799 # Select returned! 2 0.901974 # 0.699342 # Tick! 1 1.202334 # Select returned! 1 1.202508 # 0.599164 # Tick! 0 1.502823 # Select returned! 0 1.503001 # 0.498999666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1328143722.77731, t0 = 1328143722.77721, dt = 9.39369201660156e-05 # N = 2048, t1 = 1328143722.77755, t0 = 1328143722.77737, dt = 0.000181913375854492 # N = 4096, t1 = 1328143722.77796, t0 = 1328143722.77759, dt = 0.000365972518920898 # N = 8192, t1 = 1328143722.77864, t0 = 1328143722.778, dt = 0.000637054443359375 # N = 16384, t1 = 1328143722.77993, t0 = 1328143722.77868, dt = 0.00125503540039062 # N = 32768, t1 = 1328143722.78247, t0 = 1328143722.77997, dt = 0.00250697135925293 # N = 65536, t1 = 1328143722.78731, t0 = 1328143722.78251, dt = 0.00479698181152344 # N = 131072, t1 = 1328143722.79652, t0 = 1328143722.78734, dt = 0.00918102264404297 # N = 262144, t1 = 1328143722.81467, t0 = 1328143722.79655, dt = 0.0181128978729248 # Alarm 1 - 1328143722.82476 # Alarm 2 - 1328143722.86165 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 22751, terminating the watchdog process 22752 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 22752 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 22753, starting the watchdog process... # The watchdog process 22754 launched, continuing testing... # I am the watchdog process 22754, 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.038776 # clock = 0.038776 0.103741 # clock = 0.038776 0.103741 0.168695 # clock = 0.038776 0.103741 0.168695 0.233991 ok 5 # I am the main process 22753, terminating the watchdog process 22754 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 22754 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 22755, starting the watchdog process... # The watchdog process 22756 launched, continuing testing... # I am the watchdog process 22756, 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 22755, terminating the watchdog process 22756 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 22756 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 22757, starting the watchdog process... # The watchdog process 22758 launched, continuing testing... # I am the watchdog process 22758, sleeping for 360 seconds... # setitimer: 0 0 ok 1 # getitimer: 0.498461 0.4 # Tick! 2 0.507855 # Tick! 1 0.913718 # Tick! 0 1.319575 # Tick! 0 1.725375 # getitimer: 0 0 ok 2 # I am the main process 22757, terminating the watchdog process 22758 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 22758 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 22759, starting the watchdog process... # The watchdog process 22760 launched, continuing testing... # I am the watchdog process 22760, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 22759, terminating the watchdog process 22760 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 22760 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 22761, starting the watchdog process... # The watchdog process 22762 launched, continuing testing... # I am the watchdog process 22762, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1328143730.74427 ok 3 # sleep...0.500128 ok 4 # I am the main process 22761, terminating the watchdog process 22762 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 22762 = 1 # All done. ok t/stat.t .......... 1..16 # I am the main process 22763, starting the watchdog process... # The watchdog process 22764 launched, continuing testing... # I am the watchdog process 22764, 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 = 1328143731 1328143731 1328143732 1328143732 1328143732 # atime = 1328143731 1328143731 1328143732 1328143732 1328143732 # ai = 4, mi = 4, ss = 0 ok 16 # skip no subsecond timestamps detected # I am the main process 22763, terminating the watchdog process 22764 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 22764 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 22765, starting the watchdog process... # The watchdog process 22766 launched, continuing testing... # I am the watchdog process 22766, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 84.5546267032623, n = 100, s/n = 0.845546267032623 # I am the main process 22765, terminating the watchdog process 22766 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 22766 = 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 22768, starting the watchdog process... # The watchdog process 22769 launched, continuing testing... # I am the watchdog process 22769, sleeping for 360 seconds... ok 1 # tick = 1, one = 1328143732, two = 1328143732, three = 1328143732 ok 2 # tick = 3, one = 1328143732, two = 1328143732, three = 1328143732 ok 3 - negative time error # t0 = 1328143732.98191 # ualarm(100000) # t1 = 1328143733.08203 # dt = 0.100123167037964 # r = 1.00123167037964 ok 4 # t0 = 1328143733.08229 # ualarm(1100000) # t1 = 1328143734.18238 # dt = 1.1000828742981 # r = 1.000075340271 ok 5 # t0 = 1328143734.18268 # ualarm(2200000) # t1 = 1328143736.38274 # dt = 2.20005202293396 # r = 1.00002364678816 ok 6 # t0 = 1328143736.38302 # ualarm(4300000) # t1 = 1328143740.68304 # dt = 4.3000180721283 # r = 1.00000420282053 ok 7 # t0 = 1328143740.68342 # got0 = 0 # t1 = 1328143740.98342 # t1 - t0 = 0.300000905990601 # got1 = 199975 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 22768, terminating the watchdog process 22769 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 22769 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 22770, starting the watchdog process... # The watchdog process 22771 launched, continuing testing... # I am the watchdog process 22771, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 22770, terminating the watchdog process 22771 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 22771 = 1 # All done. ok All tests successful. Files=11, Tests=68, 24 wallclock secs ( 0.08 usr 0.03 sys + 14.60 cusr 0.14 csys = 14.85 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/fly1400/var/cpan/build/Time-HiRes-1.9725-XDX7Yo && tar cvf - Time-HiRes-1.9725.ppd blib) | gzip -c >/Users/fly1400/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/fly1400/var/cpan/build/Time-HiRes-1.9725-XDX7Yo/Time-HiRes-1.9725.ppd /Users/fly1400/var/REPO/Z/ZE/ZEFRAM Can't log to '/Users/fly1400/var/tmp/Library/Logs/ppm4.log': No such file or directory at /Users/fly1400/lib/ActivePerl/PPM/Logger.pm line 95. [InstallArea.pm:339] Created /Users/fly1400/var/megalib/etc/ppm-megalib-dirty [InstallArea.pm:362] Installing Time-HiRes with id 1117 [InstallArea.pm:369] Copy /var/folders/or/orXbbUKqGxyIBVdvtPjVMU+++TQ/-Tmp-/ppm-avxBd0/Time-HiRes-1.9725/blib/arch --> /Users/fly1400/var/megalib [InstallArea.pm:497] /Users/fly1400/var/megalib/auto/Time/HiRes/HiRes.bs already present [InstallArea.pm:548] /Users/fly1400/var/megalib/auto/Time/HiRes/HiRes.bundle written [InstallArea.pm:369] Copy /var/folders/or/orXbbUKqGxyIBVdvtPjVMU+++TQ/-Tmp-/ppm-avxBd0/Time-HiRes-1.9725/blib/html --> /Users/fly1400/var/megalib/html [InstallArea.pm:548] /Users/fly1400/var/megalib/html/site/lib/Time/HiRes.html written [InstallArea.pm:369] Copy /var/folders/or/orXbbUKqGxyIBVdvtPjVMU+++TQ/-Tmp-/ppm-avxBd0/Time-HiRes-1.9725/blib/lib --> /Users/fly1400/var/megalib [InstallArea.pm:548] /Users/fly1400/var/megalib/Time/HiRes.pm written [InstallArea.pm:418] Commit install [InstallArea.pm:454] unlink /Users/fly1400/var/megalib/auto/Time/HiRes/HiRes.bundle.ppmbak [InstallArea.pm:454] unlink /Users/fly1400/var/megalib/Time/HiRes.pm.ppmbak [InstallArea.pm:454] unlink /Users/fly1400/var/megalib/auto/Time/HiRes/.packlist.ppmbak [InstallArea.pm:454] unlink /Users/fly1400/var/megalib/etc/ppm-megalib-dirty Finished 2012-02-01T16:49:06