PATH=/usr/bin:/bin:/data/fly2200/cpanfly-5.22/var/megalib/bin Start 2018-03-23T00:04:26 ActivePerl-2200 CPAN-2.10 Reading '/data/fly2200/cpanfly-5.22/var/cpan/Metadata' Database was generated on Thu, 22 Mar 2018 05:54:30 GMT Checksum for /data/fly2200/cpanfly-5.22/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/fly2200/ap2200-299134/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...Processing hints file hints/linux.pl 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/fly2200/cpanfly-5.22/var/megalib Want /home/fly2200/ap2200-299134/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/fly2200/ap2200-299134/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 Time::HiRes () chmod 644 "HiRes.bs" "/data/fly2200/ap2200-299134/bin/perl-static" "/data/fly2200/cpanfly-5.22/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly2200/ap2200-299134/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 -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/fly2200/ap2200-299134/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 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so "/data/fly2200/ap2200-299134/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Manifying 1 pod document JHI/Time-HiRes-1.9758.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 "/data/fly2200/ap2200-299134/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 32064, starting the watchdog process... # The watchdog process 32065 launched, continuing testing... # I am the watchdog process 32065, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300714 # Select returned! 4 0.300765 # 0.899745 # Tick! 3 0.600933 # Select returned! 3 0.600964 # 0.799678666666667 # Tick! 2 0.901338 # Select returned! 2 0.901365 # 0.699545 # Tick! 1 1.201488 # Select returned! 1 1.201514 # 0.599495333333333 # Tick! 0 1.50257 # Select returned! 0 1.502602 # 0.499132666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1521788675.72328, t0 = 1521788675.7232, dt = 7.91549682617188e-05 # N = 2048, t1 = 1521788675.72345, t0 = 1521788675.72332, dt = 0.000129938125610352 # N = 4096, t1 = 1521788675.72454, t0 = 1521788675.72346, dt = 0.0010828971862793 # N = 8192, t1 = 1521788675.72509, t0 = 1521788675.72457, dt = 0.000526905059814453 # N = 16384, t1 = 1521788675.72617, t0 = 1521788675.72511, dt = 0.00105595588684082 # N = 32768, t1 = 1521788675.72827, t0 = 1521788675.72618, dt = 0.00208497047424316 # N = 65536, t1 = 1521788675.73356, t0 = 1521788675.72829, dt = 0.00526905059814453 # N = 131072, t1 = 1521788675.74212, t0 = 1521788675.73358, dt = 0.00853586196899414 # N = 262144, t1 = 1521788675.75887, t0 = 1521788675.74215, dt = 0.0167200565338135 # Alarm 1 - 1521788675.77053 # Alarm 2 - 1521788675.80463 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 32064, terminating the watchdog process 32065 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 32065 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 32067, starting the watchdog process... # The watchdog process 32068 launched, continuing testing... # I am the watchdog process 32068, sleeping for 360 seconds... 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 = 1521788681.54458, t0 = 1521788680.04391 # dt = 1.50067257881165, rt = 0.000448385874430413 ok 2 ok 3 ok 4 # CLOCKS_PER_SEC = 1000000 # delay = 1000000 # clock = 0.03 # clock = 0.03 0.09 # clock = 0.03 0.09 0.14 # clock = 0.03 0.09 0.14 0.21 ok 5 # I am the main process 32067, terminating the watchdog process 32068 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 32068 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 32069, starting the watchdog process... # The watchdog process 32070 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 32070, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 32069, terminating the watchdog process 32070 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 32070 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 32071, starting the watchdog process... # The watchdog process 32072 launched, continuing testing... # setitimer: 0 0 # I am the watchdog process 32072, sleeping for 360 seconds... ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # Tick! 2 0.763297 # Tick! 1 1.403277 # Tick! 0 2.023275 # Tick! 0 2.643286 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 32071, terminating the watchdog process 32072 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 32072 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 32073, starting the watchdog process... # The watchdog process 32074 launched, continuing testing... # I am the watchdog process 32074, 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 32073, terminating the watchdog process 32074 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 32074 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 32075, starting the watchdog process... # The watchdog process 32076 launched, continuing testing... # I am the watchdog process 32076, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1521788687.57251 ok 3 # sleep...0.500218 ok 4 # I am the main process 32075, terminating the watchdog process 32076 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 32076 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 32077, starting the watchdog process... # The watchdog process 32078 launched, continuing testing... # I am the watchdog process 32078, 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 = 1521788688.22801 1521788688.61201 1521788688.90001 1521788689.16401 1521788689.41602 # atime = 1521788688.42401 1521788688.764 1521788689.06401 1521788689.31201 1521788689.604 # 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 32077, terminating the watchdog process 32078 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 32078 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 32079, starting the watchdog process... # The watchdog process 32080 launched, continuing testing... # I am the watchdog process 32080, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 65.4261236190796, n = 100, s/n = 0.654261236190796 # I am the main process 32079, terminating the watchdog process 32080 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 32080 = 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 - [1521788689 693819] = 6e-06 >= 0 ok 5 - [1521788689 693819] - [1521788689 693819] = 0 ok 6 - [1521788689 793979] - [1521788689 693819] = 0.10016 (0.10016, 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 32082, starting the watchdog process... # The watchdog process 32083 launched, continuing testing... # I am the watchdog process 32083, sleeping for 360 seconds... ok 1 # tick = 1, one = 1521788689, two = 1521788689, three = 1521788689 ok 2 # tick = 3, one = 1521788689, two = 1521788689, three = 1521788689 ok 3 - negative time error # t0 = 1521788690.0015 # ualarm(100000) # t1 = 1521788690.11127 # dt = 0.109773874282837 # r = 1.09773874282837 ok 4 # t0 = 1521788690.11147 # ualarm(1100000) # t1 = 1521788691.22107 # dt = 1.10959792137146 # r = 1.00872538306496 ok 5 # t0 = 1521788691.22136 # ualarm(2200000) # t1 = 1521788693.43105 # dt = 2.20968103408813 # r = 1.00440047004006 ok 6 # t0 = 1521788693.43134 # ualarm(4300000) # t1 = 1521788697.74109 # dt = 4.30974912643433 # r = 1.00226723870566 ok 7 # t0 = 1521788697.74146 # got0 = 0 # t1 = 1521788698.04146 # t1 - t0 = 0.30000114440918 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 32082, terminating the watchdog process 32083 before it terminates me in 351 seconds (testing took 9 seconds). # kill KILL 32083 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 32084, starting the watchdog process... # The watchdog process 32085 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 32085, sleeping for 360 seconds... ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 32084, terminating the watchdog process 32085 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 32085 = 1 # All done. ok t/utime.t ......... # Testing for subsecond file timestamps (mtime) in /data/fly2200/cpanfly-5.22/var/tmp/cpan_build/Time-HiRes-1.9758-ktYJl1 # Subsecond file timestamps in /data/fly2200/cpanfly-5.22/var/tmp/cpan_build/Time-HiRes-1.9758-ktYJl1: OK 1..18 # I am the main process 32086, starting the watchdog process... # The watchdog process 32087 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 # I am the watchdog process 32087, sleeping for 360 seconds... 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 32086, terminating the watchdog process 32087 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 32087 = 1 # All done. ok All tests successful. Files=12, Tests=125, 29 wallclock secs ( 0.07 usr 0.03 sys + 15.18 cusr 0.29 csys = 15.57 CPU) Result: PASS JHI/Time-HiRes-1.9758.tar.gz make test TEST_VERBOSE=1 -- OK Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /data/fly2200/cpanfly-5.22/var/tmp/cpan_build/Time-HiRes-1.9758-ktYJl1 && tar cvf - Time-HiRes-1.9758.ppd blib) | gzip -c >/data/fly2200/cpanfly-5.22/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: 367132 kB Finished 2018-03-23T00:05:03