PATH=/usr/bin:/bin:/home/fly1400/var/megalib/bin Start 2015-11-14T00:06:49 ActivePerl-1400 CPAN-2.00 Reading '/home/fly1400/var/cpan/Metadata' Database was generated on Sat, 14 Nov 2015 07:06:02 GMT Running make for R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Fetching with LWP: http://cpan.nas1.activestate.com/authors/id/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Fetching with LWP: http://cpan.nas1.activestate.com/authors/id/R/RJ/RJBS/CHECKSUMS Checksum for /home/fly1400/var/cpan/sources/authors/id/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz ok Time-HiRes-1.9728/ Time-HiRes-1.9728/Changes Time-HiRes-1.9728/fallback/ Time-HiRes-1.9728/hints/ Time-HiRes-1.9728/HiRes.pm Time-HiRes-1.9728/HiRes.xs Time-HiRes-1.9728/Makefile.PL Time-HiRes-1.9728/MANIFEST Time-HiRes-1.9728/META.json Time-HiRes-1.9728/META.yml Time-HiRes-1.9728/ppport.h Time-HiRes-1.9728/README Time-HiRes-1.9728/t/ Time-HiRes-1.9728/TODO Time-HiRes-1.9728/typemap Time-HiRes-1.9728/t/alarm.t Time-HiRes-1.9728/t/clock.t Time-HiRes-1.9728/t/gettimeofday.t Time-HiRes-1.9728/t/itimer.t Time-HiRes-1.9728/t/nanosleep.t Time-HiRes-1.9728/t/sleep.t Time-HiRes-1.9728/t/stat.t Time-HiRes-1.9728/t/time.t Time-HiRes-1.9728/t/tv_interval.t Time-HiRes-1.9728/t/ualarm.t Time-HiRes-1.9728/t/usleep.t Time-HiRes-1.9728/t/Watchdog.pm Time-HiRes-1.9728/hints/aix.pl Time-HiRes-1.9728/hints/dec_osf.pl Time-HiRes-1.9728/hints/dynixptx.pl Time-HiRes-1.9728/hints/irix.pl Time-HiRes-1.9728/hints/linux.pl Time-HiRes-1.9728/hints/sco.pl Time-HiRes-1.9728/hints/solaris.pl Time-HiRes-1.9728/hints/svr4.pl Time-HiRes-1.9728/fallback/const-c.inc /bin/tar: Read 4608 bytes from - Time-HiRes-1.9728/fallback/const-xs.inc CPAN.pm: Building R/RJ/RJBS/Time-HiRes-1.9728.tar.gz >>> /home/fly1400/ap1400/bin/perl-static Makefile.PL Configuring Time::HiRes... Using hints hints/linux.pl... Extra libraries: -lrt... 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()... found. Looking for clock_getres()... found. Looking for clock_nanosleep()... found. Looking for clock()... found. Looking for stat() subsecond timestamps... Trying struct stat st_atimespec.tv_nsec...NOT found. Trying struct stat st_atimensec...NOT found. Trying struct stat st_atime_n...NOT found. Trying struct stat st_atim.tv_nsec...found. Trying struct stat st_uatime...Processing hints file hints/linux.pl 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 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'. >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/home/fly1400/ap1400/bin/perl-static" "/home/fly1400/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly1400/ap1400/lib/ExtUtils/typemap" -typemap "typemap" HiRes.xs > HiRes.xsc && mv HiRes.xsc HiRes.c gcc -c -D_REENTRANT -D_GNU_SOURCE -DUSE_SITECUSTOMIZE -DPERL_RELOCATABLE_INCPUSH -fno-merge-constants -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9728\" -DXS_VERSION=\"1.9728\" -fPIC "-I/home/fly1400/ap1400/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT=4 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.so LD_RUN_PATH="/lib64" gcc -shared -O2 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so "/home/fly1400/ap1400/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Manifying 1 pod document RJBS/Time-HiRes-1.9728.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 "/home/fly1400/ap1400/bin/perl-static" "-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 15386, starting the watchdog process... # I am the watchdog process 15387, sleeping for 360 seconds... # The watchdog process 15387 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.557948 # Select returned! 4 0.558401 # 0.813866333333333 # Tick! 3 0.860954 # Select returned! 3 0.861319 # 0.712893666666667 # Tick! 2 1.161896 # Select returned! 2 1.162139 # 0.612620333333333 # Tick! 1 1.462981 # Select returned! 1 1.463055 # 0.512315 # Tick! 0 1.76409 # Select returned! 0 1.764174 # 0.411942 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1447488433.22334, t0 = 1447488433.22323, dt = 0.000108003616333008 # N = 2048, t1 = 1447488433.22362, t0 = 1447488433.22341, dt = 0.000210046768188477 # N = 4096, t1 = 1447488433.22405, t0 = 1447488433.22368, dt = 0.000371932983398438 # N = 8192, t1 = 1447488433.22512, t0 = 1447488433.22419, dt = 0.000929117202758789 # N = 16384, t1 = 1447488433.22725, t0 = 1447488433.22539, dt = 0.00186419486999512 # N = 32768, t1 = 1447488433.23095, t0 = 1447488433.22729, dt = 0.00365900993347168 # N = 65536, t1 = 1447488433.23893, t0 = 1447488433.23107, dt = 0.00786185264587402 # N = 131072, t1 = 1447488433.25331, t0 = 1447488433.23909, dt = 0.01422119140625 # Alarm 1 - 1447488433.26468 # Alarm 2 - 1447488433.29387 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 15386, terminating the watchdog process 15387 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 15387 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 15388, starting the watchdog process... # I am the watchdog process 15389, sleeping for 360 seconds... # The watchdog process 15389 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 = 1447488439.09181, t0 = 1447488437.58864 # dt = 1.503173828125, rt = 0.00211588541666674 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.12 # clock = 0.03 0.12 0.22 # clock = 0.03 0.12 0.22 0.31 ok 5 # I am the main process 15388, terminating the watchdog process 15389 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 15389 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 15390, starting the watchdog process... # I am the watchdog process 15391, sleeping for 360 seconds... # The watchdog process 15391 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 15390, terminating the watchdog process 15391 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 15391 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 15393, starting the watchdog process... # I am the watchdog process 15394, sleeping for 360 seconds... # The watchdog process 15394 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.500923 0.400939 # Tick! 2 1.077531 # Tick! 1 1.885501 # Tick! 0 2.699067 # Tick! 0 3.508756 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 15393, terminating the watchdog process 15394 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 15394 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 15397, starting the watchdog process... # I am the watchdog process 15398, sleeping for 360 seconds... # The watchdog process 15398 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 15397, terminating the watchdog process 15398 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 15398 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 15399, starting the watchdog process... # I am the watchdog process 15400, sleeping for 360 seconds... # The watchdog process 15400 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1447488446.23781 ok 3 # sleep...0.500724 ok 4 # I am the main process 15399, terminating the watchdog process 15400 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 15400 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 15401, starting the watchdog process... # I am the watchdog process 15402, sleeping for 360 seconds... # The watchdog process 15402 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 = 1447488446 1447488447 1447488447 1447488447 1447488448 # atime = 1447488447 1447488447 1447488447 1447488447 1447488448 # 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 15401, terminating the watchdog process 15402 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 15402 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 15403, starting the watchdog process... # I am the watchdog process 15404, sleeping for 360 seconds... # The watchdog process 15404 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 36.9704184532166, n = 100, s/n = 0.369704184532166 # I am the main process 15403, terminating the watchdog process 15404 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 15404 = 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 15406, starting the watchdog process... # I am the watchdog process 15407, sleeping for 360 seconds... # The watchdog process 15407 launched, continuing testing... ok 1 # tick = 1, one = 1447488448, two = 1447488448, three = 1447488448 ok 2 # tick = 3, one = 1447488448, two = 1447488448, three = 1447488448 ok 3 - negative time error # t0 = 1447488448.66574 # ualarm(100000) # t1 = 1447488448.76726 # dt = 0.101517915725708 # r = 1.01517915725708 ok 4 # t0 = 1447488448.76901 # ualarm(1100000) # t1 = 1447488449.9229 # dt = 1.15389108657837 # r = 1.04899189688943 ok 5 # t0 = 1447488449.92453 # ualarm(2200000) # t1 = 1447488452.13491 # dt = 2.21037697792053 # r = 1.0047168081457 ok 6 # t0 = 1447488452.13615 # ualarm(4300000) # t1 = 1447488456.46418 # dt = 4.32803392410278 # r = 1.00651951723321 ok 7 # t0 = 1447488456.46583 # got0 = 0 # t1 = 1447488456.77038 # t1 - t0 = 0.304542064666748 # got1 = 195446 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 15406, terminating the watchdog process 15407 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 15407 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 15408, starting the watchdog process... # I am the watchdog process 15409, sleeping for 360 seconds... # The watchdog process 15409 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 15408, terminating the watchdog process 15409 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 15409 = 1 # All done. ok All tests successful. Files=11, Tests=95, 29 wallclock secs ( 0.08 usr 0.04 sys + 9.00 cusr 0.15 csys = 9.27 CPU) Result: PASS RJBS/Time-HiRes-1.9728.tar.gz make test TEST_VERBOSE=1 -- OK Ricardo SIGNES <rjbs@cpan.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1400/var/cpan/build/Time-HiRes-1.9728-inJ5AR && tar cvf - Time-HiRes-1.9728.ppd blib) | gzip -c >/home/fly1400/var/REPO/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Time-HiRes-1.9728.ppd blib/ blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.so blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1400/var/cpan/build/Time-HiRes-1.9728-inJ5AR/Time-HiRes-1.9728.ppd /home/fly1400/var/REPO/R/RJ/RJBS VmSize: 271056 kB Finished 2015-11-14T00:07:41