PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2018-02-18T02:23:10 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Sat, 17 Feb 2018 05:17:03 GMT Checksum for /data/fly2400/cpanfly-5.24/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9754.tar.gz ok Time-HiRes-1.9754/ Time-HiRes-1.9754/HiRes.xs Time-HiRes-1.9754/fallback/ Time-HiRes-1.9754/Changes Time-HiRes-1.9754/HiRes.pm Time-HiRes-1.9754/MANIFEST Time-HiRes-1.9754/ppport.h Time-HiRes-1.9754/t/ Time-HiRes-1.9754/README Time-HiRes-1.9754/TODO Time-HiRes-1.9754/typemap Time-HiRes-1.9754/META.yml Time-HiRes-1.9754/Makefile.PL Time-HiRes-1.9754/hints/ Time-HiRes-1.9754/META.json Time-HiRes-1.9754/hints/dynixptx.pl Time-HiRes-1.9754/hints/sco.pl Time-HiRes-1.9754/hints/svr4.pl Time-HiRes-1.9754/hints/dec_osf.pl Time-HiRes-1.9754/hints/solaris.pl Time-HiRes-1.9754/hints/aix.pl Time-HiRes-1.9754/hints/linux.pl Time-HiRes-1.9754/hints/irix.pl Time-HiRes-1.9754/t/utime.t Time-HiRes-1.9754/t/time.t Time-HiRes-1.9754/t/sleep.t Time-HiRes-1.9754/t/tv_interval.t Time-HiRes-1.9754/t/itimer.t Time-HiRes-1.9754/t/Watchdog.pm Time-HiRes-1.9754/t/alarm.t Time-HiRes-1.9754/t/stat.t Time-HiRes-1.9754/t/nanosleep.t Time-HiRes-1.9754/t/gettimeofday.t Time-HiRes-1.9754/t/usleep.t Time-HiRes-1.9754/t/clock.t Time-HiRes-1.9754/t/ualarm.t Time-HiRes-1.9754/fallback/const-c.inc Time-HiRes-1.9754/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9754.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.9754.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9754.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.9754-tGM6lh/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.9754\" -DXS_VERSION=\"1.9754\" -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.9754.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 4323, starting the watchdog process... # The watchdog process 4324 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # I am the watchdog process 4324, sleeping for 360 seconds... # Tick! 4 0.301269 # Select returned! 4 0.301322 # 0.899559333333333 # Tick! 3 0.602165 # Select returned! 3 0.602192 # 0.799269333333333 # Tick! 2 0.902422 # Select returned! 2 0.902449 # 0.699183666666667 # Tick! 1 1.203343 # Select returned! 1 1.20337 # 0.598876666666667 # Tick! 0 1.503494 # Select returned! 0 1.50352 # 0.498826666666667 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1518949400.31934, t0 = 1518949400.31926, dt = 8.10623168945312e-05 # N = 2048, t1 = 1518949400.31954, t0 = 1518949400.31936, dt = 0.000174045562744141 # N = 4096, t1 = 1518949400.31987, t0 = 1518949400.31955, dt = 0.000317811965942383 # N = 8192, t1 = 1518949400.32052, t0 = 1518949400.31987, dt = 0.000644922256469727 # N = 16384, t1 = 1518949400.32277, t0 = 1518949400.32053, dt = 0.00224685668945312 # N = 32768, t1 = 1518949400.32536, t0 = 1518949400.3228, dt = 0.00255990028381348 # N = 65536, t1 = 1518949400.33044, t0 = 1518949400.32537, dt = 0.00506997108459473 # N = 131072, t1 = 1518949400.341, t0 = 1518949400.33045, dt = 0.0105528831481934 # Alarm 1 - 1518949400.36101 # Alarm 2 - 1518949400.3951 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 4323, terminating the watchdog process 4324 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 4324 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 4325, starting the watchdog process... # The watchdog process 4326 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 4326, sleeping for 360 seconds... # have_clock_gettime = 1 # have_clock_getres = 1 # have_clock_nanosleep = 1 # have_clock = 1 # CLOCK_REALTIME: try = 1 # t1 = 1518949406.10824, t0 = 1518949404.60478 # dt = 1.50346040725708, rt = 0.00230693817138672 ok 2 ok 3 ok 4 # CLOCKS_PER_SEC = 1000000 # delay = 1000000 # clock = 0.09 # clock = 0.09 0.16 # clock = 0.09 0.16 0.25 # clock = 0.09 0.16 0.25 0.32 ok 5 # I am the main process 4325, terminating the watchdog process 4326 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 4326 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 4327, starting the watchdog process... # The watchdog process 4328 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 4328, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 4327, terminating the watchdog process 4328 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 4328 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 4329, starting the watchdog process... # The watchdog process 4330 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # I am the watchdog process 4330, sleeping for 360 seconds... # Tick! 2 0.788267 # Tick! 1 1.408244 # Tick! 0 2.01801 # Tick! 0 2.626964 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 4329, terminating the watchdog process 4330 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 4330 = 1 # All done. ok t/nanosleep.t ..... 1..4 # I am the main process 4331, starting the watchdog process... # The watchdog process 4332 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 4332, sleeping for 360 seconds... ok 2 ok 3 - nanosleep for more than 0.4 sec ok 4 # I am the main process 4331, terminating the watchdog process 4332 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 4332 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 4333, starting the watchdog process... # The watchdog process 4334 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 4334, sleeping for 360 seconds... ok 2 - negative time error # time...1518949412.4037 ok 3 # sleep...0.501564 ok 4 # I am the main process 4333, terminating the watchdog process 4334 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 4334 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 4335, starting the watchdog process... # The watchdog process 4336 launched, continuing testing... # I am the watchdog process 4336, 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 = 1518949413.1674 1518949413.4114 1518949413.6834 1518949413.9194 1518949414.1994 # atime = 1518949413.2954 1518949413.5554 1518949413.7914 1518949414.0634 1518949414.3834 # 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 4335, terminating the watchdog process 4336 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 4336 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 4337, starting the watchdog process... # The watchdog process 4338 launched, continuing testing... # I am the watchdog process 4338, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 # s = 48.7315790653229, n = 100, s/n = 0.487315790653229 # I am the main process 4337, terminating the watchdog process 4338 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 4338 = 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 4340, starting the watchdog process... # The watchdog process 4341 launched, continuing testing... # I am the watchdog process 4341, sleeping for 360 seconds... ok 1 # tick = 1, one = 1518949414, two = 1518949414, three = 1518949414 ok 2 # tick = 3, one = 1518949414, two = 1518949414, three = 1518949414 ok 3 - negative time error # t0 = 1518949414.7416 # ualarm(100000) # t1 = 1518949414.85098 # dt = 0.109378099441528 # r = 1.09378099441528 ok 4 # t0 = 1518949414.85117 # ualarm(1100000) # t1 = 1518949415.961 # dt = 1.10982799530029 # r = 1.00893454118208 ok 5 # t0 = 1518949415.96125 # ualarm(2200000) # t1 = 1518949418.17098 # dt = 2.20973706245422 # r = 1.00442593747919 ok 6 # t0 = 1518949418.17129 # ualarm(4300000) # t1 = 1518949422.481 # dt = 4.30971097946167 # r = 1.00225836731667 ok 7 # t0 = 1518949422.48131 # got0 = 0 # t1 = 1518949422.78131 # t1 - t0 = 0.300000905990601 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 4340, terminating the watchdog process 4341 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 4341 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 4342, starting the watchdog process... # The watchdog process 4343 launched, continuing testing... # I am the watchdog process 4343, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 4342, terminating the watchdog process 4343 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 4343 = 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.9754-tGM6lh # Subsecond file timestamps in /data/fly2400/cpanfly-5.24/var/tmp/cpan_build/Time-HiRes-1.9754-tGM6lh: OK 1..18 # I am the main process 4344, starting the watchdog process... # The watchdog process 4345 launched, continuing testing... # I am the watchdog process 4345, sleeping for 360 seconds... # $^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 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 4344, terminating the watchdog process 4345 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 4345 = 1 # All done. ok All tests successful. Files=12, Tests=114, 30 wallclock secs ( 0.06 usr 0.03 sys + 15.77 cusr 0.30 csys = 16.16 CPU) Result: PASS JHI/Time-HiRes-1.9754.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.9754-tGM6lh && tar cvf - Time-HiRes-1.9754.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9754.tar.gz Time-HiRes-1.9754.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: 356912 kB Finished 2018-02-18T02:23:48