PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2017-08-17T04:44:15 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Thu, 17 Aug 2017 05:17:03 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9745.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /data/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9745.tar.gz ok Time-HiRes-1.9745/ Time-HiRes-1.9745/Changes Time-HiRes-1.9745/fallback/ Time-HiRes-1.9745/hints/ Time-HiRes-1.9745/HiRes.pm Time-HiRes-1.9745/HiRes.xs Time-HiRes-1.9745/Makefile.PL Time-HiRes-1.9745/MANIFEST Time-HiRes-1.9745/META.json Time-HiRes-1.9745/META.yml Time-HiRes-1.9745/ppport.h Time-HiRes-1.9745/README Time-HiRes-1.9745/t/ Time-HiRes-1.9745/TODO Time-HiRes-1.9745/typemap Time-HiRes-1.9745/t/alarm.t Time-HiRes-1.9745/t/clock.t Time-HiRes-1.9745/t/gettimeofday.t Time-HiRes-1.9745/t/itimer.t Time-HiRes-1.9745/t/nanosleep.t Time-HiRes-1.9745/t/sleep.t Time-HiRes-1.9745/t/stat.t Time-HiRes-1.9745/t/time.t Time-HiRes-1.9745/t/tv_interval.t Time-HiRes-1.9745/t/ualarm.t Time-HiRes-1.9745/t/usleep.t Time-HiRes-1.9745/t/utime.t Time-HiRes-1.9745/t/Watchdog.pm Time-HiRes-1.9745/hints/aix.pl Time-HiRes-1.9745/hints/dec_osf.pl Time-HiRes-1.9745/hints/dynixptx.pl Time-HiRes-1.9745/hints/irix.pl Time-HiRes-1.9745/hints/linux.pl Time-HiRes-1.9745/hints/sco.pl Time-HiRes-1.9745/hints/solaris.pl Time-HiRes-1.9745/hints/svr4.pl Time-HiRes-1.9745/fallback/const-c.inc Time-HiRes-1.9745/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9745.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.9745.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9745.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.9745-dKywnj/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.9745\" -DXS_VERSION=\"1.9745\" -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.9745.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 12681, starting the watchdog process... # The watchdog process 12682 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 12682, sleeping for 360 seconds... # sigaction tick, ALRM = 14 # Tick! 4 0.300731 # Select returned! 4 0.300793 # 0.899735666666667 # Tick! 3 0.6009 # Select returned! 3 0.600942 # 0.799686 # Tick! 2 0.901057 # Select returned! 2 0.90109 # 0.699636666666667 # Tick! 1 1.201265 # Select returned! 1 1.201333 # 0.599555666666667 # Tick! 0 1.501456 # Select returned! 0 1.501504 # 0.499498666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1502970265.5581, t0 = 1502970265.55802, dt = 8.29696655273438e-05 # N = 2048, t1 = 1502970265.55829, t0 = 1502970265.55812, dt = 0.000164031982421875 # N = 4096, t1 = 1502970265.55862, t0 = 1502970265.5583, dt = 0.000326871871948242 # N = 8192, t1 = 1502970265.55928, t0 = 1502970265.55863, dt = 0.00065302848815918 # N = 16384, t1 = 1502970265.56193, t0 = 1502970265.55929, dt = 0.00263404846191406 # N = 32768, t1 = 1502970265.5646, t0 = 1502970265.56195, dt = 0.00265097618103027 # N = 65536, t1 = 1502970265.56992, t0 = 1502970265.56461, dt = 0.00530385971069336 # N = 131072, t1 = 1502970265.58081, t0 = 1502970265.56993, dt = 0.0108780860900879 # Alarm 1 - 1502970265.60085 # Alarm 2 - 1502970265.621 # Alarm 3 - 1502970265.64242 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 12681, terminating the watchdog process 12682 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 12682 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 12683, starting the watchdog process... # The watchdog process 12684 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 12684, sleeping for 360 seconds... # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # CLOCK_REALTIME: try = 1 # t1 = 1502970271.33429, t0 = 1502970269.83405 # dt = 1.50024485588074, rt = 0.000163237253824944 ok 2 ok 3 ok 4 # clock = 0.06 # clock = 0.06 0.12 # clock = 0.06 0.12 0.17 # clock = 0.06 0.12 0.17 0.23 ok 5 # I am the main process 12683, terminating the watchdog process 12684 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 12684 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 12685, starting the watchdog process... # The watchdog process 12686 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 12686, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 12685, terminating the watchdog process 12686 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 12686 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 12687, starting the watchdog process... # The watchdog process 12689 launched, continuing testing... # setitimer: 0 0 # I am the watchdog process 12689, sleeping for 360 seconds... ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # Tick! 2 0.720531 # Tick! 1 1.341078 # Tick! 0 1.940847 # Tick! 0 2.560385 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 12687, terminating the watchdog process 12689 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 12689 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 12691, starting the watchdog process... # The watchdog process 12692 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 12692, sleeping for 360 seconds... ok 2 ok 3 # I am the main process 12691, terminating the watchdog process 12692 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 12692 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 12693, starting the watchdog process... # The watchdog process 12694 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1502970277.40849 ok 3 # I am the watchdog process 12694, sleeping for 360 seconds... # sleep...0.500126 ok 4 # I am the main process 12693, terminating the watchdog process 12694 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 12694 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 12695, starting the watchdog process... # The watchdog process 12696 launched, continuing testing... # I am the watchdog process 12696, 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 = 1502970278.14645 1502970278.40645 1502970278.74245 1502970279.06645 1502970279.32645 # atime = 1502970278.25844 1502970278.54645 1502970278.87844 1502970279.18645 1502970279.49845 # 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 12695, terminating the watchdog process 12696 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 12696 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 12697, starting the watchdog process... # The watchdog process 12698 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 58.0171718597412, n = 100, s/n = 0.580171718597412 # I am the main process 12697, terminating the watchdog process 12698 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 12698 = 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 12700, starting the watchdog process... # The watchdog process 12701 launched, continuing testing... # I am the watchdog process 12701, sleeping for 360 seconds... ok 1 # tick = 1, one = 1502970279, two = 1502970279, three = 1502970279 ok 2 # tick = 3, one = 1502970279, two = 1502970279, three = 1502970279 ok 3 - negative time error # t0 = 1502970279.80139 # ualarm(100000) # t1 = 1502970279.91077 # dt = 0.109377861022949 # r = 1.09377861022949 ok 4 # t0 = 1502970279.91077 # ualarm(1100000) # t1 = 1502970281.02086 # dt = 1.11009812355042 # r = 1.00918011231856 ok 5 # t0 = 1502970281.02124 # ualarm(2200000) # t1 = 1502970283.23087 # dt = 2.20963001251221 # r = 1.00437727841464 ok 6 # t0 = 1502970283.23123 # ualarm(4300000) # t1 = 1502970287.54087 # dt = 4.30963778495789 # r = 1.00224134533904 ok 7 # t0 = 1502970287.54125 # got0 = 0 # t1 = 1502970287.84126 # t1 - t0 = 0.300000905990601 # got1 = 200003 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 12700, terminating the watchdog process 12701 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 12701 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 12830, starting the watchdog process... # The watchdog process 12831 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 12831, sleeping for 360 seconds... ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 12830, terminating the watchdog process 12831 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 12831 = 1 # All done. ok # Testing for subsecond file timestamps (mtime) in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9745-dKywnj # Subsecond file timestamps in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9745-dKywnj: OK t/utime.t ......... 1..18 # I am the main process 12832, starting the watchdog process... # The watchdog process 12833 launched, continuing testing... # $^O = linux, atime = 1.111111111, mtime = 2.222222222 # utime $fh # I am the watchdog process 12833, 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 12832, terminating the watchdog process 12833 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 12833 = 1 # All done. ok All tests successful. Files=12, Tests=113, 29 wallclock secs ( 0.06 usr 0.04 sys + 15.09 cusr 0.27 csys = 15.46 CPU) Result: PASS JHI/Time-HiRes-1.9745.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.9745-dKywnj && tar cvf - Time-HiRes-1.9745.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9745.tar.gz Time-HiRes-1.9745.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: 358152 kB Finished 2017-08-17T04:44:52