PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2018-01-06T02:17:09 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Fri, 05 Jan 2018 05:17:02 GMT Checksum for /data/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9752.tar.gz ok Time-HiRes-1.9752/ Time-HiRes-1.9752/HiRes.xs Time-HiRes-1.9752/fallback/ Time-HiRes-1.9752/Changes Time-HiRes-1.9752/HiRes.pm Time-HiRes-1.9752/MANIFEST Time-HiRes-1.9752/ppport.h Time-HiRes-1.9752/t/ Time-HiRes-1.9752/README Time-HiRes-1.9752/TODO Time-HiRes-1.9752/typemap Time-HiRes-1.9752/META.yml Time-HiRes-1.9752/Makefile.PL Time-HiRes-1.9752/hints/ Time-HiRes-1.9752/META.json Time-HiRes-1.9752/hints/dynixptx.pl Time-HiRes-1.9752/hints/sco.pl Time-HiRes-1.9752/hints/svr4.pl Time-HiRes-1.9752/hints/dec_osf.pl Time-HiRes-1.9752/hints/solaris.pl Time-HiRes-1.9752/hints/aix.pl Time-HiRes-1.9752/hints/linux.pl Time-HiRes-1.9752/hints/irix.pl Time-HiRes-1.9752/t/utime.t Time-HiRes-1.9752/t/time.t Time-HiRes-1.9752/t/sleep.t Time-HiRes-1.9752/t/tv_interval.t Time-HiRes-1.9752/t/itimer.t Time-HiRes-1.9752/t/Watchdog.pm Time-HiRes-1.9752/t/alarm.t Time-HiRes-1.9752/t/stat.t Time-HiRes-1.9752/t/nanosleep.t Time-HiRes-1.9752/t/gettimeofday.t Time-HiRes-1.9752/t/usleep.t Time-HiRes-1.9752/t/clock.t Time-HiRes-1.9752/t/ualarm.t Time-HiRes-1.9752/fallback/const-c.inc Time-HiRes-1.9752/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9752.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.9752.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9752.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.9752-UX1uPg/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.9752\" -DXS_VERSION=\"1.9752\" -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.9752.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 24421, starting the watchdog process... # The watchdog process 24422 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 24422, sleeping for 360 seconds... # sigaction tick, ALRM = 14 # Tick! 4 0.300682 # Select returned! 4 0.300742 # 0.899752666666667 # Tick! 3 0.60113 # Select returned! 3 0.601192 # 0.799602666666667 # Tick! 2 0.901361 # Select returned! 2 0.901393 # 0.699535666666667 # Tick! 1 1.202991 # Select returned! 1 1.203024 # 0.598992 # Tick! 0 1.503209 # Select returned! 0 1.503241 # 0.498919666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1515233838.92596, t0 = 1515233838.92587, dt = 8.10623168945312e-05 # N = 2048, t1 = 1515233838.92614, t0 = 1515233838.92598, dt = 0.0001678466796875 # N = 4096, t1 = 1515233838.92647, t0 = 1515233838.92616, dt = 0.000317811965942383 # N = 8192, t1 = 1515233838.92713, t0 = 1515233838.92648, dt = 0.000644922256469727 # N = 16384, t1 = 1515233838.92841, t0 = 1515233838.92713, dt = 0.00127100944519043 # N = 32768, t1 = 1515233838.93093, t0 = 1515233838.92842, dt = 0.00251197814941406 # N = 65536, t1 = 1515233838.9375, t0 = 1515233838.93094, dt = 0.00656390190124512 # N = 131072, t1 = 1515233838.94703, t0 = 1515233838.93752, dt = 0.00951194763183594 # N = 262144, t1 = 1515233838.96711, t0 = 1515233838.94739, dt = 0.0197222232818604 # Alarm 1 - 1515233838.98269 # Alarm 2 - 1515233839.02638 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 24421, terminating the watchdog process 24422 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 24422 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 24423, starting the watchdog process... # The watchdog process 24424 launched, continuing testing... ok 1 - require Time::HiRes; # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # I am the watchdog process 24424, sleeping for 360 seconds... # CLOCK_REALTIME: try = 1 # t1 = 1515233844.88535, t0 = 1515233843.38439 # dt = 1.50096225738525, rt = 0.000641504923502678 ok 2 ok 3 ok 4 # clock = 0.08 # clock = 0.08 0.14 # clock = 0.08 0.14 0.2 # clock = 0.08 0.14 0.2 0.25 ok 5 # I am the main process 24423, terminating the watchdog process 24424 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 24424 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 24425, starting the watchdog process... # The watchdog process 24426 launched, continuing testing... # gettimeofday returned 2 args ok 1 # I am the watchdog process 24426, sleeping for 360 seconds... ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 24425, terminating the watchdog process 24426 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 24426 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 24427, starting the watchdog process... # The watchdog process 24428 launched, continuing testing... # setitimer: 0 0 # I am the watchdog process 24428, sleeping for 360 seconds... ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # Tick! 2 0.787469 # Tick! 1 1.400728 # Tick! 0 2.020726 # Tick! 0 2.607645 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 24427, terminating the watchdog process 24428 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 24428 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 24429, starting the watchdog process... # The watchdog process 24430 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 24430, sleeping for 360 seconds... ok 2 ok 3 - nanosleep for more than 0.4 sec ok 4 # I am the main process 24429, terminating the watchdog process 24430 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 24430 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 24431, starting the watchdog process... # The watchdog process 24432 launched, continuing testing... # I am the watchdog process 24432, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1515233851.08012 ok 3 # sleep...0.500952 ok 4 # I am the main process 24431, terminating the watchdog process 24432 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 24432 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 24433, starting the watchdog process... # The watchdog process 24434 launched, continuing testing... # I am the watchdog process 24434, 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 = 1515233851.85452 1515233852.18253 1515233852.51053 1515233852.72252 1515233853.01852 # atime = 1515233852.03052 1515233852.31452 1515233852.61852 1515233852.90252 1515233853.12652 # 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 24433, terminating the watchdog process 24434 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 24434 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 24435, starting the watchdog process... # The watchdog process 24436 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 23.1797456741333, n = 100, s/n = 0.231797456741333 # I am the main process 24435, terminating the watchdog process 24436 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 24436 = 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 24438, starting the watchdog process... # The watchdog process 24439 launched, continuing testing... # I am the watchdog process 24439, sleeping for 360 seconds... ok 1 # tick = 1, one = 1515233853, two = 1515233853, three = 1515233853 ok 2 # tick = 3, one = 1515233853, two = 1515233853, three = 1515233853 ok 3 - negative time error # t0 = 1515233853.49355 # ualarm(100000) # t1 = 1515233853.6029 # dt = 0.109347820281982 # r = 1.09347820281982 ok 4 # t0 = 1515233853.6031 # ualarm(1100000) # t1 = 1515233854.71293 # dt = 1.10982298851013 # r = 1.00892998955467 ok 5 # t0 = 1515233854.7132 # ualarm(2200000) # t1 = 1515233856.92293 # dt = 2.20973181724548 # r = 1.0044235532934 ok 6 # t0 = 1515233856.9233 # ualarm(4300000) # t1 = 1515233861.23294 # dt = 4.30963492393494 # r = 1.00224067998487 ok 7 # t0 = 1515233861.23327 # got0 = 0 # t1 = 1515233861.53327 # t1 - t0 = 0.300000905990601 # got1 = 200002 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 24438, terminating the watchdog process 24439 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 24439 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 24440, starting the watchdog process... # The watchdog process 24441 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 24441, sleeping for 360 seconds... ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 24440, terminating the watchdog process 24441 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 24441 = 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.9752-UX1uPg # Subsecond file timestamps in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9752-UX1uPg: OK 1..18 # I am the main process 24442, starting the watchdog process... # The watchdog process 24443 launched, continuing testing... # $^O = linux, atime = 1.111111111, mtime = 2.222222222 # utime $fh 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 # I am the watchdog process 24443, sleeping for 360 seconds... 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 24442, terminating the watchdog process 24443 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 24443 = 1 # All done. ok All tests successful. Files=12, Tests=114, 29 wallclock secs ( 0.06 usr 0.04 sys + 15.98 cusr 0.21 csys = 16.29 CPU) Result: PASS JHI/Time-HiRes-1.9752.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.9752-UX1uPg && tar cvf - Time-HiRes-1.9752.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9752.tar.gz Time-HiRes-1.9752.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: 356424 kB Finished 2018-01-06T02:17:47