PATH=/usr/bin:/bin:/data/fly2400/cpanfly-5.24/var/megalib/bin Start 2016-06-27T08:09:22 ActivePerl-2400 CPAN-2.10 Reading '/data/fly2400/cpanfly-5.24/var/cpan/Metadata' Database was generated on Mon, 27 Jun 2016 04:29:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9738.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.9738.tar.gz ok Time-HiRes-1.9738/ Time-HiRes-1.9738/Changes Time-HiRes-1.9738/fallback/ Time-HiRes-1.9738/hints/ Time-HiRes-1.9738/HiRes.pm Time-HiRes-1.9738/HiRes.xs Time-HiRes-1.9738/Makefile.PL Time-HiRes-1.9738/MANIFEST Time-HiRes-1.9738/META.json Time-HiRes-1.9738/META.yml Time-HiRes-1.9738/ppport.h Time-HiRes-1.9738/README Time-HiRes-1.9738/t/ Time-HiRes-1.9738/TODO Time-HiRes-1.9738/typemap Time-HiRes-1.9738/t/alarm.t Time-HiRes-1.9738/t/clock.t Time-HiRes-1.9738/t/gettimeofday.t Time-HiRes-1.9738/t/itimer.t Time-HiRes-1.9738/t/nanosleep.t Time-HiRes-1.9738/t/sleep.t Time-HiRes-1.9738/t/stat.t Time-HiRes-1.9738/t/time.t Time-HiRes-1.9738/t/tv_interval.t Time-HiRes-1.9738/t/ualarm.t Time-HiRes-1.9738/t/usleep.t Time-HiRes-1.9738/t/utime.t Time-HiRes-1.9738/t/Watchdog.pm Time-HiRes-1.9738/hints/aix.pl Time-HiRes-1.9738/hints/dec_osf.pl Time-HiRes-1.9738/hints/dynixptx.pl Time-HiRes-1.9738/hints/irix.pl Time-HiRes-1.9738/hints/linux.pl Time-HiRes-1.9738/hints/sco.pl Time-HiRes-1.9738/hints/solaris.pl Time-HiRes-1.9738/hints/svr4.pl Time-HiRes-1.9738/fallback/const-c.inc Time-HiRes-1.9738/fallback/const-xs.inc Configuring J/JH/JHI/Time-HiRes-1.9738.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 clock_gettime()... found. Looking for clock_getres()... found. Looking for clock_nanosleep()... found. Looking for clock()... found. Looking for futimens()... found. Looking for utimensat()... found. 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 stat() subsecond timestamps. (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.9738.tar.gz /data/fly2400/ap2400-300558/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9738.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.9738-lnjtWE/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.9738\" -DXS_VERSION=\"1.9738\" -fPIC "-I/home/fly2400/ap2400-300558/lib/CORE" -DTIME_HIRES_NANOSLEEP -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.9738.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 7975, starting the watchdog process... # The watchdog process 7976 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 7976, sleeping for 360 seconds... # sigaction tick, ALRM = 14 # Tick! 4 0.30018 # Select returned! 4 0.300242 # 0.899919333333333 # Tick! 3 0.600496 # Select returned! 3 0.60054 # 0.79982 # Tick! 2 0.90066 # Select returned! 2 0.900702 # 0.699766 # Tick! 1 1.200937 # Select returned! 1 1.200979 # 0.599673666666667 # Tick! 0 1.50112 # Select returned! 0 1.501163 # 0.499612333333333 # ok 2 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1467040173.49599, t0 = 1467040173.4959, dt = 8.20159912109375e-05 # N = 2048, t1 = 1467040173.49619, t0 = 1467040173.49603, dt = 0.000159025192260742 # N = 4096, t1 = 1467040173.49652, t0 = 1467040173.4962, dt = 0.00031590461730957 # N = 8192, t1 = 1467040173.49715, t0 = 1467040173.49652, dt = 0.000626802444458008 # N = 16384, t1 = 1467040173.49919, t0 = 1467040173.49717, dt = 0.00202012062072754 # N = 32768, t1 = 1467040173.50245, t0 = 1467040173.49922, dt = 0.00322580337524414 # N = 65536, t1 = 1467040173.50774, t0 = 1467040173.50245, dt = 0.0052940845489502 # N = 131072, t1 = 1467040173.51836, t0 = 1467040173.50784, dt = 0.0105199813842773 # Alarm 1 - 1467040173.52856 # Alarm 2 - 1467040173.55102 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 7975, terminating the watchdog process 7976 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 7976 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 7977, starting the watchdog process... # The watchdog process 7978 launched, continuing testing... # I am the watchdog process 7978, 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 = 1467040179.3042, t0 = 1467040177.80394 # dt = 1.50025820732117, rt = 0.000172138214111328 ok 2 ok 3 ok 4 # clock = 0.1 # clock = 0.1 0.16 # clock = 0.1 0.16 0.22 # clock = 0.1 0.16 0.22 0.28 ok 5 # I am the main process 7977, terminating the watchdog process 7978 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 7978 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 7979, starting the watchdog process... # The watchdog process 7980 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 # I am the watchdog process 7980, sleeping for 360 seconds... ok 3 ok 4 ok 5 ok 6 # I am the main process 7979, terminating the watchdog process 7980 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7980 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 7981, starting the watchdog process... # The watchdog process 7982 launched, continuing testing... # I am the watchdog process 7982, sleeping for 360 seconds... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.504031 0.400025 # Tick! 2 0.506906 # Tick! 1 0.910158 # Tick! 0 1.310222 # Tick! 0 1.71407 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 7981, terminating the watchdog process 7982 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7982 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 7983, starting the watchdog process... # The watchdog process 7984 launched, continuing testing... # I am the watchdog process 7984, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 # I am the main process 7983, terminating the watchdog process 7984 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7984 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 7985, starting the watchdog process... # The watchdog process 7986 launched, continuing testing... # I am the watchdog process 7986, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1467040184.569 ok 3 # sleep...0.500167 ok 4 # I am the main process 7985, terminating the watchdog process 7986 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7986 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 7987, starting the watchdog process... # The watchdog process 7988 launched, continuing testing... # I am the watchdog process 7988, 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 = 1467040185.33566 1467040185.55567 1467040185.87569 1467040186.21171 1467040186.55972 # atime = 1467040185.44367 1467040185.71168 1467040186.0477 1467040186.38371 1467040186.75573 # 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 7987, terminating the watchdog process 7988 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 7988 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 7989, starting the watchdog process... # The watchdog process 7990 launched, continuing testing... ok 1 - require Time::HiRes; # I am the watchdog process 7990, sleeping for 360 seconds... ok 2 # s = 85.0787627696991, n = 100, s/n = 0.850787627696991 # I am the main process 7989, terminating the watchdog process 7990 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7990 = 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 7992, starting the watchdog process... # The watchdog process 7993 launched, continuing testing... # I am the watchdog process 7993, sleeping for 360 seconds... ok 1 # tick = 1, one = 1467040187, two = 1467040187, three = 1467040187 ok 2 # tick = 3, one = 1467040187, two = 1467040187, three = 1467040187 ok 3 - negative time error # t0 = 1467040187.07747 # ualarm(100000) # t1 = 1467040187.1776 # dt = 0.100136041641235 # r = 1.00136041641235 ok 4 # t0 = 1467040187.17782 # ualarm(1100000) # t1 = 1467040188.27839 # dt = 1.10057282447815 # r = 1.00052074952559 ok 5 # t0 = 1467040188.27866 # ualarm(2200000) # t1 = 1467040190.47879 # dt = 2.2001268863678 # r = 1.00005767562173 ok 6 # t0 = 1467040190.4791 # ualarm(4300000) # t1 = 1467040194.77918 # dt = 4.30007982254028 # r = 1.00001856338146 ok 7 # t0 = 1467040194.77958 # got0 = 0 # t1 = 1467040195.07958 # t1 - t0 = 0.300000905990601 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 7992, terminating the watchdog process 7993 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 7993 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 7994, starting the watchdog process... # The watchdog process 7995 launched, continuing testing... # I am the watchdog process 7995, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 7994, terminating the watchdog process 7995 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 7995 = 1 # All done. ok t/utime.t ......... 1..18 # I am the main process 7996, starting the watchdog process... # The watchdog process 7997 launched, continuing testing... # I am the watchdog process 7997, sleeping for 360 seconds... # 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 7996, terminating the watchdog process 7997 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 7997 = 1 # All done. ok All tests successful. Files=12, Tests=113, 28 wallclock secs ( 0.09 usr 0.02 sys + 14.94 cusr 0.17 csys = 15.22 CPU) Result: PASS JHI/Time-HiRes-1.9738.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.9738-lnjtWE && tar cvf - Time-HiRes-1.9738.ppd blib) | gzip -c >/data/fly2400/cpanfly-5.24/var/REPO/J/JH/JHI/Time-HiRes-1.9738.tar.gz Time-HiRes-1.9738.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: 336716 kB Finished 2016-06-27T08:09:59