PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2017-12-23T02:09:13 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Fri, 22 Dec 2017 04:17:03 GMT Checksum for /data/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9749.tar.gz ok Time-HiRes-1.9749/ Time-HiRes-1.9749/HiRes.xs Time-HiRes-1.9749/fallback/ Time-HiRes-1.9749/Changes Time-HiRes-1.9749/HiRes.pm Time-HiRes-1.9749/MANIFEST Time-HiRes-1.9749/ppport.h Time-HiRes-1.9749/t/ Time-HiRes-1.9749/README Time-HiRes-1.9749/TODO Time-HiRes-1.9749/typemap Time-HiRes-1.9749/META.yml Time-HiRes-1.9749/Makefile.PL Time-HiRes-1.9749/hints/ Time-HiRes-1.9749/META.json Time-HiRes-1.9749/hints/dynixptx.pl Time-HiRes-1.9749/hints/sco.pl Time-HiRes-1.9749/hints/svr4.pl Time-HiRes-1.9749/hints/dec_osf.pl Time-HiRes-1.9749/hints/solaris.pl Time-HiRes-1.9749/hints/aix.pl Time-HiRes-1.9749/hints/linux.pl Time-HiRes-1.9749/hints/irix.pl Time-HiRes-1.9749/t/utime.t Time-HiRes-1.9749/t/time.t Time-HiRes-1.9749/t/sleep.t Time-HiRes-1.9749/t/tv_interval.t Time-HiRes-1.9749/t/itimer.t Time-HiRes-1.9749/t/Watchdog.pm Time-HiRes-1.9749/t/alarm.t Time-HiRes-1.9749/t/stat.t Time-HiRes-1.9749/t/nanosleep.t Time-HiRes-1.9749/t/gettimeofday.t Time-HiRes-1.9749/t/usleep.t Time-HiRes-1.9749/t/clock.t Time-HiRes-1.9749/t/ualarm.t Time-HiRes-1.9749/fallback/const-c.inc Time-HiRes-1.9749/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9749.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.9749.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9749.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.9749-wE_DpM/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.9749\" -DXS_VERSION=\"1.9749\" -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.9749.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 27491, starting the watchdog process... # The watchdog process 27492 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 27492, sleeping for 360 seconds... # sigaction tick, ALRM = 14 # Tick! 4 0.30085 # Select returned! 4 0.300916 # 0.899694666666667 # Tick! 3 0.601065 # Select returned! 3 0.601107 # 0.799631 # Tick! 2 0.901584 # Select returned! 2 0.901618 # 0.699460666666667 # Tick! 1 1.202261 # Select returned! 1 1.202295 # 0.599235 # Tick! 0 1.502811 # Select returned! 0 1.502843 # 0.499052333333333 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1514023763.78198, t0 = 1514023763.7819, dt = 8.10623168945312e-05 # N = 2048, t1 = 1514023763.78216, t0 = 1514023763.782, dt = 0.000159978866577148 # N = 4096, t1 = 1514023763.7825, t0 = 1514023763.78217, dt = 0.000329971313476562 # N = 8192, t1 = 1514023763.78314, t0 = 1514023763.78251, dt = 0.000626087188720703 # N = 16384, t1 = 1514023763.78442, t0 = 1514023763.78315, dt = 0.00127005577087402 # N = 32768, t1 = 1514023763.787, t0 = 1514023763.78443, dt = 0.0025780200958252 # N = 65536, t1 = 1514023763.7921, t0 = 1514023763.78701, dt = 0.00508689880371094 # N = 131072, t1 = 1514023763.80333, t0 = 1514023763.79211, dt = 0.011218786239624 # Alarm 1 - 1514023763.82341 # Alarm 2 - 1514023763.84356 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 27491, terminating the watchdog process 27492 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 27492 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 27493, starting the watchdog process... # The watchdog process 27494 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 27494, sleeping for 360 seconds... # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # CLOCK_REALTIME: try = 1 # t1 = 1514023769.55681, t0 = 1514023768.05505 # dt = 1.50176405906677, rt = 0.00117603937784838 ok 2 ok 3 ok 4 # clock = 0.08 # clock = 0.08 0.14 # clock = 0.08 0.14 0.18 # clock = 0.08 0.14 0.18 0.25 ok 5 # I am the main process 27493, terminating the watchdog process 27494 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 27494 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 27495, starting the watchdog process... # The watchdog process 27496 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 27496, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 27495, terminating the watchdog process 27496 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 27496 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 27497, starting the watchdog process... # The watchdog process 27498 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # I am the watchdog process 27498, sleeping for 360 seconds... # Tick! 2 0.796288 # Tick! 1 1.426513 # Tick! 0 2.026511 # Tick! 0 2.646524 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 27497, terminating the watchdog process 27498 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 27498 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 27500, starting the watchdog process... # The watchdog process 27501 launched, continuing testing... # I am the watchdog process 27501, 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 27500, terminating the watchdog process 27501 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 27501 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 27502, starting the watchdog process... # The watchdog process 27503 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 27503, sleeping for 360 seconds... ok 2 - negative time error # time...1514023775.79455 ok 3 # sleep...0.500318 ok 4 # I am the main process 27502, terminating the watchdog process 27503 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 27503 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 27504, starting the watchdog process... # The watchdog process 27505 launched, continuing testing... # I am the watchdog process 27505, 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 = 1514023776.57934 1514023776.81934 1514023777.18734 1514023777.45933 1514023777.69934 # atime = 1514023776.71534 1514023777.00734 1514023777.29934 1514023777.57934 1514023777.86334 # 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 27504, terminating the watchdog process 27505 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 27505 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 27506, starting the watchdog process... # The watchdog process 27507 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 27507, sleeping for 360 seconds... ok 2 # s = 96.8176543712616, n = 100, s/n = 0.968176543712616 # I am the main process 27506, terminating the watchdog process 27507 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 27507 = 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 27509, starting the watchdog process... # The watchdog process 27510 launched, continuing testing... # I am the watchdog process 27510, sleeping for 360 seconds... ok 1 # tick = 1, one = 1514023778, two = 1514023778, three = 1514023778 ok 2 # tick = 3, one = 1514023778, two = 1514023778, three = 1514023778 ok 3 - negative time error # t0 = 1514023778.21613 # ualarm(100000) # t1 = 1514023778.32327 # dt = 0.107139110565186 # r = 1.07139110565186 ok 4 # t0 = 1514023778.32355 # ualarm(1100000) # t1 = 1514023779.43342 # dt = 1.10986304283142 # r = 1.00896640257402 ok 5 # t0 = 1514023779.43378 # ualarm(2200000) # t1 = 1514023781.64338 # dt = 2.20960116386414 # r = 1.00436416539279 ok 6 # t0 = 1514023781.64371 # ualarm(4300000) # t1 = 1514023785.95339 # dt = 4.30968189239502 # r = 1.00225160288256 ok 7 # t0 = 1514023785.95374 # got0 = 0 # t1 = 1514023786.25375 # t1 - t0 = 0.30000114440918 # got1 = 200002 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 27509, terminating the watchdog process 27510 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 27510 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 27511, starting the watchdog process... # The watchdog process 27512 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 27512, sleeping for 360 seconds... ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 27511, terminating the watchdog process 27512 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 27512 = 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.9749-wE_DpM # Subsecond file timestamps in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9749-wE_DpM: OK 1..18 # I am the main process 27513, starting the watchdog process... # The watchdog process 27514 launched, continuing testing... # $^O = linux, atime = 1.111111111, mtime = 2.222222222 # utime $fh # I am the watchdog process 27514, 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 27513, terminating the watchdog process 27514 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 27514 = 1 # All done. ok All tests successful. Files=12, Tests=114, 29 wallclock secs ( 0.06 usr 0.04 sys + 15.71 cusr 0.35 csys = 16.16 CPU) Result: PASS JHI/Time-HiRes-1.9749.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.9749-wE_DpM && tar cvf - Time-HiRes-1.9749.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9749.tar.gz Time-HiRes-1.9749.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: 356188 kB Finished 2017-12-23T02:09:51