PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2018-03-23T02:03:23 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Thu, 22 Mar 2018 05:54:30 GMT Checksum for /data/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9758.tar.gz ok Time-HiRes-1.9758/ Time-HiRes-1.9758/HiRes.xs Time-HiRes-1.9758/fallback/ Time-HiRes-1.9758/Changes Time-HiRes-1.9758/HiRes.pm Time-HiRes-1.9758/MANIFEST Time-HiRes-1.9758/ppport.h Time-HiRes-1.9758/t/ Time-HiRes-1.9758/README Time-HiRes-1.9758/TODO Time-HiRes-1.9758/typemap Time-HiRes-1.9758/META.yml Time-HiRes-1.9758/Makefile.PL Time-HiRes-1.9758/hints/ Time-HiRes-1.9758/META.json Time-HiRes-1.9758/hints/dynixptx.pl Time-HiRes-1.9758/hints/sco.pl Time-HiRes-1.9758/hints/svr4.pl Time-HiRes-1.9758/hints/dec_osf.pl Time-HiRes-1.9758/hints/solaris.pl Time-HiRes-1.9758/hints/aix.pl Time-HiRes-1.9758/hints/linux.pl Time-HiRes-1.9758/hints/irix.pl Time-HiRes-1.9758/t/utime.t Time-HiRes-1.9758/t/time.t Time-HiRes-1.9758/t/sleep.t Time-HiRes-1.9758/t/tv_interval.t Time-HiRes-1.9758/t/itimer.t Time-HiRes-1.9758/t/Watchdog.pm Time-HiRes-1.9758/t/alarm.t Time-HiRes-1.9758/t/stat.t Time-HiRes-1.9758/t/nanosleep.t Time-HiRes-1.9758/t/gettimeofday.t Time-HiRes-1.9758/t/usleep.t Time-HiRes-1.9758/t/clock.t Time-HiRes-1.9758/t/ualarm.t Time-HiRes-1.9758/fallback/const-c.inc Time-HiRes-1.9758/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9758.tar.gz with Makefile.PL >>> /data/fly2400/ap2400-300558/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 clockid_t... found. Looking for clock_gettime()... found. Looking for clock_getres()... found. Looking for clock_nanosleep()... found. Looking for clock()... found. Looking for working futimens()... found. Looking for working utimensat()... found. You seem to have subsecond timestamp setting. 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...NOT found. You seem to have subsecond timestamp reading. (Your struct stat has them, but the filesystems must help.) Checking if your kit is complete... Looks good Have /data/fly2400/cpanfly-5.24/var/megalib Want /home/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: [x86_64-linux-thread-multi] 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.9758.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9758.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for HiRes () chmod 644 "HiRes.bs" "/data/fly2400/ap2400-300558/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 "/data/fly2400/ap2400-300558/bin/perl-static" "/data/fly2400/cpanfly-5.24/var/megalib/ExtUtils/xsubpp" -typemap '/home/fly2400/ap2400-300558/lib/ExtUtils/typemap' -typemap '/data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9758-wF6UPg/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 -fwrapv -fno-strict-aliasing -pipe -fstack-protector -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9758\" -DXS_VERSION=\"1.9758\" -fPIC "-I/home/fly2400/ap2400-300558/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCKID_T -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DHAS_FUTIMENS -DHAS_UTIMENSAT -DTIME_HIRES_UTIME -DTIME_HIRES_STAT_XTIM -DTIME_HIRES_STAT=4 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.so LD_RUN_PATH="/lib/x86_64-linux-gnu" gcc -shared -O2 -fstack-protector -o blib/arch/auto/Time/HiRes/HiRes.so HiRes.o \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so Manifying 1 pod document JHI/Time-HiRes-1.9758.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 "/data/fly2400/ap2400-300558/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 PERL_DL_NONLAZY=1 "/data/fly2400/ap2400-300558/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 9638, starting the watchdog process... # The watchdog process 9639 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # I am the watchdog process 9639, sleeping for 360 seconds... # Tick! 4 0.301656 # Select returned! 4 0.301704 # 0.899432 # Tick! 3 0.603959 # Select returned! 3 0.603986 # 0.798671333333333 # Tick! 2 0.904233 # Select returned! 2 0.904261 # 0.698579666666667 # Tick! 1 1.204841 # Select returned! 1 1.204868 # 0.598377333333333 # Tick! 0 1.505786 # Select returned! 0 1.505811 # 0.498063 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1521795813.50118, t0 = 1521795813.5011, dt = 8.10623168945312e-05 # N = 2048, t1 = 1521795813.50135, t0 = 1521795813.50119, dt = 0.000159978866577148 # N = 4096, t1 = 1521795813.50172, t0 = 1521795813.50136, dt = 0.000352144241333008 # N = 8192, t1 = 1521795813.50239, t0 = 1521795813.50174, dt = 0.000647068023681641 # N = 16384, t1 = 1521795813.50374, t0 = 1521795813.50241, dt = 0.00133204460144043 # N = 32768, t1 = 1521795813.50639, t0 = 1521795813.50377, dt = 0.00262093544006348 # N = 65536, t1 = 1521795813.51331, t0 = 1521795813.50641, dt = 0.00689601898193359 # N = 131072, t1 = 1521795813.52417, t0 = 1521795813.51333, dt = 0.0108380317687988 # Alarm 1 - 1521795813.53909 # Alarm 2 - 1521795813.56101 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 9638, terminating the watchdog process 9639 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 9639 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 9640, starting the watchdog process... # The watchdog process 9641 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 # I am the watchdog process 9641, sleeping for 360 seconds... # t1 = 1521795819.28944, t0 = 1521795817.78811 # dt = 1.50133562088013, rt = 0.000890413920084709 ok 2 ok 3 ok 4 # CLOCKS_PER_SEC = 1000000 # delay = 1000000 # clock = 0.09 # clock = 0.09 0.17 # clock = 0.09 0.17 0.25 # clock = 0.09 0.17 0.25 0.33 ok 5 # I am the main process 9640, terminating the watchdog process 9641 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 9641 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 9642, starting the watchdog process... # The watchdog process 9643 launched, continuing testing... # gettimeofday returned 2 args # I am the watchdog process 9643, sleeping for 360 seconds... ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 9642, terminating the watchdog process 9643 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9643 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 9644, starting the watchdog process... # The watchdog process 9645 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # I am the watchdog process 9645, sleeping for 360 seconds... # Tick! 2 0.840075 # Tick! 1 1.479915 # Tick! 0 2.099909 # Tick! 0 2.759913 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 9644, terminating the watchdog process 9645 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 9645 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 9646, starting the watchdog process... # The watchdog process 9647 launched, continuing testing... # I am the watchdog process 9647, 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 9646, terminating the watchdog process 9647 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 9647 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 9648, starting the watchdog process... # The watchdog process 9649 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1521795825.74608 ok 3 # I am the watchdog process 9649, sleeping for 360 seconds... # sleep...0.500235 ok 4 # I am the main process 9648, terminating the watchdog process 9649 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9649 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 9650, starting the watchdog process... # The watchdog process 9651 launched, continuing testing... # I am the watchdog process 9651, 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 = 1521795826.48061 1521795826.7686 1521795827.12461 1521795827.5166 1521795827.8126 # atime = 1521795826.62461 1521795826.93661 1521795827.32461 1521795827.70061 1521795827.94061 # ai = 4, mi = 4, ss = 8 ok 36 ok 37 ok 38 ok 39 ok 40 ok 41 ok 42 ok 43 # I am the main process 9650, terminating the watchdog process 9651 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9651 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 9652, starting the watchdog process... # The watchdog process 9653 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 9653, sleeping for 360 seconds... ok 2 # s = 5.1609480381012, n = 100, s/n = 0.051609480381012 # I am the main process 9652, terminating the watchdog process 9653 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 9653 = 1 # All done. ok t/tv_interval.t ... 1..13 ok 1 - require Time::HiRes; ok 2 - 10.5 - 5.1 = 5.4 ok 3 - [] - [] = 0 ok 4 - now - [1521795828 158179] = 5e-06 >= 0 ok 5 - [1521795828 158179] - [1521795828 158179] = 0 ok 6 - [1521795828 258449] - [1521795828 158179] = 0.10027 (0.10027, 1) ok 7 - at least one arg ok 8 - 1st arg aref ok 9 - 1st arg aref ok 10 - 1st arg aref ok 11 - 2nd arg aref ok 12 - 2nd arg aref ok 13 - no extra decimals ok t/ualarm.t ........ 1..12 # I am the main process 9655, starting the watchdog process... # The watchdog process 9656 launched, continuing testing... # I am the watchdog process 9656, sleeping for 360 seconds... ok 1 # tick = 1, one = 1521795828, two = 1521795828, three = 1521795828 ok 2 # tick = 3, one = 1521795828, two = 1521795828, three = 1521795828 ok 3 - negative time error # t0 = 1521795828.53038 # ualarm(100000) # t1 = 1521795828.63942 # dt = 0.10904598236084 # r = 1.0904598236084 ok 4 # t0 = 1521795828.64012 # ualarm(1100000) # t1 = 1521795829.74975 # dt = 1.10962986946106 # r = 1.00875442678278 ok 5 # t0 = 1521795829.74996 # ualarm(2200000) # t1 = 1521795831.95985 # dt = 2.20988798141479 # r = 1.00449453700672 ok 6 # t0 = 1521795831.9601 # ualarm(4300000) # t1 = 1521795836.27404 # dt = 4.31394195556641 # r = 1.003242315248 ok 7 # t0 = 1521795836.27432 # got0 = 0 # t1 = 1521795836.57432 # t1 - t0 = 0.30000114440918 # got1 = 200000 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 9655, terminating the watchdog process 9656 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 9656 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 9657, starting the watchdog process... # The watchdog process 9658 launched, continuing testing... # I am the watchdog process 9658, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 9657, terminating the watchdog process 9658 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 9658 = 1 # All done. ok t/utime.t ......... # Testing for subsecond file timestamps (mtime) in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9758-wF6UPg # Subsecond file timestamps in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9758-wF6UPg: OK 1..18 # I am the main process 9659, starting the watchdog process... # The watchdog process 9660 launched, continuing testing... # $^O = linux, atime = 1.111111111, mtime = 2.222222222 # utime $fh # I am the watchdog process 9660, sleeping for 360 seconds... ok 1 - One file changed ok 2 - atime set correctly ok 3 - mtime set correctly #utime $filename ok 4 - One file changed ok 5 - atime set correctly ok 6 - mtime set correctly utime $filename and $fh ok 7 - Two files changed ok 8 - File 1 atime set correctly ok 9 - File 1 mtime set correctly ok 10 - File 2 atime set correctly ok 11 - File 2 mtime set correctly # utime undef sets time to now ok 12 - Two files changed ok 13 - File 1 atime set correctly ok 14 - File 1 mtime set correctly ok 15 - File 2 atime set correctly ok 16 - File 2 mtime set correctly # negative atime dies ok 17 - negative time error # negative mtime dies; ok 18 - negative time error # I am the main process 9659, terminating the watchdog process 9660 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 9660 = 1 # All done. ok All tests successful. Files=12, Tests=125, 30 wallclock secs ( 0.04 usr 0.06 sys + 15.98 cusr 0.37 csys = 16.45 CPU) Result: PASS JHI/Time-HiRes-1.9758.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9758-wF6UPg && tar cvf - Time-HiRes-1.9758.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9758.tar.gz Time-HiRes-1.9758.ppd blib/ blib/man3/ blib/man3/Time::HiRes.3 blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.so blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm VmSize: 358536 kB Finished 2018-03-23T02:04:02