PATH=/usr/bin:/bin:/home/fly1600/cpanfly-5.16/var/megalib/bin Start 2016-03-14T14:02:08 ActivePerl-1600 CPAN-2.10 Reading '/home/fly1600/cpanfly-5.16/var/cpan/Metadata' Database was generated on Mon, 14 Mar 2016 05:53:41 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9732.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /home/fly1600/cpanfly-5.16/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9732.tar.gz ok Time-HiRes-1.9732/ Time-HiRes-1.9732/Changes Time-HiRes-1.9732/fallback/ Time-HiRes-1.9732/hints/ Time-HiRes-1.9732/HiRes.pm Time-HiRes-1.9732/HiRes.xs Time-HiRes-1.9732/Makefile.PL Time-HiRes-1.9732/MANIFEST Time-HiRes-1.9732/META.json Time-HiRes-1.9732/META.yml Time-HiRes-1.9732/ppport.h Time-HiRes-1.9732/README Time-HiRes-1.9732/t/ Time-HiRes-1.9732/TODO Time-HiRes-1.9732/typemap Time-HiRes-1.9732/t/alarm.t Time-HiRes-1.9732/t/clock.t Time-HiRes-1.9732/t/gettimeofday.t Time-HiRes-1.9732/t/itimer.t Time-HiRes-1.9732/t/nanosleep.t Time-HiRes-1.9732/t/sleep.t Time-HiRes-1.9732/t/stat.t Time-HiRes-1.9732/t/time.t Time-HiRes-1.9732/t/tv_interval.t Time-HiRes-1.9732/t/ualarm.t Time-HiRes-1.9732/t/usleep.t Time-HiRes-1.9732/t/Watchdog.pm Time-HiRes-1.9732/hints/aix.pl Time-HiRes-1.9732/hints/dec_osf.pl Time-HiRes-1.9732/hints/dynixptx.pl Time-HiRes-1.9732/hints/irix.pl Time-HiRes-1.9732/hints/linux.pl Time-HiRes-1.9732/hints/sco.pl Time-HiRes-1.9732/hints/solaris.pl Time-HiRes-1.9732/hints/svr4.pl Time-HiRes-1.9732/fallback/const-c.inc Time-HiRes-1.9732/fallback/const-xs.inc /bin/tar: Read 1536 bytes from - Configuring J/JH/JHI/Time-HiRes-1.9732.tar.gz with Makefile.PL >>> /home/fly1600/ap1600/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 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 stat() subsecond timestamps. (Your struct stat has them, but the filesystems must help.) Checking if your kit is complete... Looks good Have /home/fly1600/cpanfly-5.16/var/megalib Want /home/fly1600/ap1600/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 Now you may issue 'make'. Do not forget also 'make test'. JHI/Time-HiRes-1.9732.tar.gz /home/fly1600/ap1600/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9732.tar.gz Could not read metadata file. Falling back to other methods to determine prerequisites >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/home/fly1600/ap1600/bin/perl-static" "/home/fly1600/cpanfly-5.16/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly1600/ap1600/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 -fno-strict-aliasing -pipe -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9732\" -DXS_VERSION=\"1.9732\" -fPIC "-I/home/fly1600/ap1600/lib/CORE" -DTIME_HIRES_NANOSLEEP -DTIME_HIRES_CLOCK_GETTIME -DTIME_HIRES_CLOCK_GETRES -DTIME_HIRES_CLOCK_NANOSLEEP -DTIME_HIRES_CLOCK -DTIME_HIRES_STAT=4 -DATLEASTFIVEOHOHFIVE HiRes.c rm -f blib/arch/auto/Time/HiRes/HiRes.so LD_RUN_PATH="/lib64" gcc -shared -O2 HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so "/home/fly1600/ap1600/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Use of uninitialized value $dirs[0] in string eq at /home/fly1600/cpanfly-5.16/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /home/fly1600/cpanfly-5.16/var/megalib/Pod/Man.pm line 894. Manifying 1 pod document JHI/Time-HiRes-1.9732.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 "/home/fly1600/ap1600/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 13969, starting the watchdog process... # I am the watchdog process 13970, sleeping for 360 seconds... # The watchdog process 13970 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300491 # Select returned! 4 0.30062 # 0.899793333333333 # Tick! 3 0.601475 # Select returned! 3 0.601599 # 0.799467 # Tick! 2 0.902487 # Select returned! 2 0.90261 # 0.69913 # Tick! 1 1.203526 # Select returned! 1 1.203601 # 0.598799666666667 # Tick! 0 1.504503 # Select returned! 0 1.504585 # 0.498471666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1457989339.80663, t0 = 1457989339.80655, dt = 7.79628753662109e-05 # N = 2048, t1 = 1457989339.80685, t0 = 1457989339.8067, dt = 0.00014805793762207 # N = 4096, t1 = 1457989339.80746, t0 = 1457989339.80691, dt = 0.000555038452148438 # N = 8192, t1 = 1457989339.80851, t0 = 1457989339.80753, dt = 0.000978946685791016 # N = 16384, t1 = 1457989339.80956, t0 = 1457989339.80858, dt = 0.000971078872680664 # N = 32768, t1 = 1457989339.81152, t0 = 1457989339.80956, dt = 0.00196695327758789 # N = 65536, t1 = 1457989339.81719, t0 = 1457989339.8116, dt = 0.00558614730834961 # N = 131072, t1 = 1457989339.82759, t0 = 1457989339.81737, dt = 0.0102250576019287 # Alarm 1 - 1457989339.83883 # Alarm 2 - 1457989339.85858 # Alarm 3 - 1457989339.8781 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 13969, terminating the watchdog process 13970 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 13970 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 13972, starting the watchdog process... # I am the watchdog process 13973, sleeping for 360 seconds... # The watchdog process 13973 launched, continuing testing... 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 = 1457989345.54563, t0 = 1457989344.04431 # dt = 1.50132203102112, rt = 0.00088135401407885 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.1 # clock = 0.03 0.1 0.17 # clock = 0.03 0.1 0.17 0.25 ok 5 # I am the main process 13972, terminating the watchdog process 13973 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 13973 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 13974, starting the watchdog process... # I am the watchdog process 13975, sleeping for 360 seconds... # The watchdog process 13975 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 13974, terminating the watchdog process 13975 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13975 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 13976, starting the watchdog process... # I am the watchdog process 13977, sleeping for 360 seconds... # The watchdog process 13977 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.498924 0.400939 # Tick! 2 0.509592 # Tick! 1 0.917521 # Tick! 0 1.32157 # Tick! 0 1.725489 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 13976, terminating the watchdog process 13977 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 13977 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 13978, starting the watchdog process... # I am the watchdog process 13979, sleeping for 360 seconds... # The watchdog process 13979 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 13978, terminating the watchdog process 13979 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 13979 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 13980, starting the watchdog process... # I am the watchdog process 13981, sleeping for 360 seconds... # The watchdog process 13981 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1457989350.70881 ok 3 # sleep...0.50035 ok 4 # I am the main process 13980, terminating the watchdog process 13981 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13981 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 13982, starting the watchdog process... # I am the watchdog process 13983, sleeping for 360 seconds... # The watchdog process 13983 launched, continuing testing... 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 = 1457989351 1457989351 1457989351 1457989352 1457989352 # atime = 1457989351 1457989351 1457989352 1457989352 1457989352 # ai = 4, mi = 4, ss = 0 ok 36 # skip no subsecond timestamps detected ok 37 ok 38 ok 39 ok 40 ok 41 ok 42 ok 43 # I am the main process 13982, terminating the watchdog process 13983 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 13983 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 13984, starting the watchdog process... # I am the watchdog process 13985, sleeping for 360 seconds... # The watchdog process 13985 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 79.748743057251, n = 100, s/n = 0.79748743057251 # I am the main process 13984, terminating the watchdog process 13985 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 13985 = 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 13987, starting the watchdog process... # I am the watchdog process 13988, sleeping for 360 seconds... # The watchdog process 13988 launched, continuing testing... ok 1 # tick = 1, one = 1457989352, two = 1457989352, three = 1457989352 ok 2 # tick = 3, one = 1457989352, two = 1457989352, three = 1457989352 ok 3 - negative time error # t0 = 1457989352.93776 # ualarm(100000) # t1 = 1457989353.03878 # dt = 0.101028919219971 # r = 1.01028919219971 ok 4 # t0 = 1457989353.04048 # ualarm(1100000) # t1 = 1457989354.14203 # dt = 1.10154700279236 # r = 1.00140636617487 ok 5 # t0 = 1457989354.14367 # ualarm(2200000) # t1 = 1457989356.34594 # dt = 2.20227003097534 # r = 1.00103183226152 ok 6 # t0 = 1457989356.34688 # ualarm(4300000) # t1 = 1457989360.64788 # dt = 4.30100393295288 # r = 1.00023347277974 ok 7 # t0 = 1457989360.64967 # got0 = 0 # t1 = 1457989360.94967 # t1 - t0 = 0.300004005432129 # got1 = 200001 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 13987, terminating the watchdog process 13988 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 13988 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 13990, starting the watchdog process... # I am the watchdog process 13991, sleeping for 360 seconds... # The watchdog process 13991 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 13990, terminating the watchdog process 13991 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 13991 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.05 usr 0.05 sys + 14.47 cusr 0.12 csys = 14.69 CPU) Result: PASS JHI/Time-HiRes-1.9732.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1600/cpanfly-5.16/var/cpan/build/Time-HiRes-1.9732-Nx0ANr && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/home/fly1600/cpanfly-5.16/var/REPO/J/JH/JHI/Time-HiRes-1.9732.tar.gz Time-HiRes-1.9732.ppd blib/ blib/lib/ blib/lib/Time/ blib/lib/Time/HiRes.pm blib/arch/ blib/arch/auto/ blib/arch/auto/Time/ blib/arch/auto/Time/HiRes/ blib/arch/auto/Time/HiRes/HiRes.so blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1600/cpanfly-5.16/var/cpan/build/Time-HiRes-1.9732-Nx0ANr/Time-HiRes-1.9732.ppd /home/fly1600/cpanfly-5.16/var/REPO/J/JH/JHI VmSize: 278452 kB Finished 2016-03-14T14:02:45