PATH=/usr/bin:/bin:/data/fly2000/cpanfly-5.20/var/megalib/bin Start 2016-03-14T04:17:39 ActivePerl-2000 CPAN-2.10 Reading '/data/fly2000/cpanfly-5.20/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 /data/fly2000/cpanfly-5.20/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 Configuring J/JH/JHI/Time-HiRes-1.9732.tar.gz with Makefile.PL >>> /data/fly2000/ap2000-298540/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 /data/fly2000/cpanfly-5.20/var/megalib Want /home/fly2000/ap2000-298540/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.9732.tar.gz /data/fly2000/ap2000-298540/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9732.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/data/fly2000/ap2000-298540/bin/perl-static" "/data/fly2000/cpanfly-5.20/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly2000/ap2000-298540/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.9732\" -DXS_VERSION=\"1.9732\" -fPIC "-I/home/fly2000/ap2000-298540/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="/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/fly2000/ap2000-298540/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 /data/fly2000/cpanfly-5.20/var/megalib/Pod/Man.pm line 893. Use of uninitialized value $dirs[0] in string eq at /data/fly2000/cpanfly-5.20/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 "/data/fly2000/ap2000-298540/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 23874, starting the watchdog process... # The watchdog process 23875 launched, continuing testing... # I am the watchdog process 23875, sleeping for 360 seconds... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300362 # Select returned! 4 0.300465 # 0.899845 # Tick! 3 0.600641 # Select returned! 3 0.600716 # 0.799761333333333 # Tick! 2 0.901705 # Select returned! 2 0.901785 # 0.699405 # Tick! 1 1.201964 # Select returned! 1 1.202035 # 0.599321666666667 # Tick! 0 1.502241 # Select returned! 0 1.50239 # 0.499203333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1457954270.69661, t0 = 1457954270.69654, dt = 7.70092010498047e-05 # N = 2048, t1 = 1457954270.69681, t0 = 1457954270.69668, dt = 0.000127077102661133 # N = 4096, t1 = 1457954270.69712, t0 = 1457954270.69688, dt = 0.000249862670898438 # N = 8192, t1 = 1457954270.69766, t0 = 1457954270.69715, dt = 0.000504016876220703 # N = 16384, t1 = 1457954270.69873, t0 = 1457954270.6977, dt = 0.00102400779724121 # N = 32768, t1 = 1457954270.70144, t0 = 1457954270.69878, dt = 0.00266218185424805 # N = 65536, t1 = 1457954270.70646, t0 = 1457954270.70151, dt = 0.00495004653930664 # N = 131072, t1 = 1457954270.71513, t0 = 1457954270.70652, dt = 0.00860500335693359 # N = 262144, t1 = 1457954270.73263, t0 = 1457954270.71522, dt = 0.0174131393432617 # Alarm 1 - 1457954270.74291 # Alarm 2 - 1457954270.78138 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 23874, terminating the watchdog process 23875 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 23875 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 23876, starting the watchdog process... # The watchdog process 23877 launched, continuing testing... # I am the watchdog process 23877, 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 = 1457954276.57396, t0 = 1457954275.07368 # dt = 1.50027680397034, rt = 0.000184535980224609 ok 2 ok 3 ok 4 # clock = 0.05 # clock = 0.05 0.12 # clock = 0.05 0.12 0.18 # clock = 0.05 0.12 0.18 0.25 ok 5 # I am the main process 23876, terminating the watchdog process 23877 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 23877 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 23878, starting the watchdog process... # The watchdog process 23879 launched, continuing testing... # gettimeofday returned 2 args # I am the watchdog process 23879, sleeping for 360 seconds... ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 23878, terminating the watchdog process 23879 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 23879 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 23880, starting the watchdog process... # The watchdog process 23881 launched, continuing testing... # setitimer: 0 0 # I am the watchdog process 23881, sleeping for 360 seconds... ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.500031 0.400025 # Tick! 2 0.50534 # Tick! 1 0.905314 # Tick! 0 1.317318 # Tick! 0 1.718027 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 23880, terminating the watchdog process 23881 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 23881 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 23882, starting the watchdog process... # The watchdog process 23883 launched, continuing testing... ok 1 - negative time error # I am the watchdog process 23883, sleeping for 360 seconds... ok 2 ok 3 # I am the main process 23882, terminating the watchdog process 23883 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 23883 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 23884, starting the watchdog process... # The watchdog process 23885 launched, continuing testing... # I am the watchdog process 23885, sleeping for 360 seconds... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1457954281.73293 ok 3 # sleep...0.500151 ok 4 # I am the main process 23884, terminating the watchdog process 23885 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 23885 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 23886, starting the watchdog process... # The watchdog process 23887 launched, continuing testing... # I am the watchdog process 23887, 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 = 1457954282.39932 1457954282.74331 1457954283.05532 1457954283.37532 1457954283.70332 # atime = 1457954282.54732 1457954282.87532 1457954283.21532 1457954283.57133 1457954283.82732 # 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 23886, terminating the watchdog process 23887 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 23887 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 23888, starting the watchdog process... # I am the watchdog process 23889, sleeping for 360 seconds... # The watchdog process 23889 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 87.7767992019653, n = 100, s/n = 0.877767992019653 # I am the main process 23888, terminating the watchdog process 23889 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 23889 = 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 23891, starting the watchdog process... # The watchdog process 23892 launched, continuing testing... # I am the watchdog process 23892, sleeping for 360 seconds... ok 1 # tick = 1, one = 1457954283, two = 1457954283, three = 1457954283 ok 2 # tick = 3, one = 1457954283, two = 1457954283, three = 1457954283 ok 3 - negative time error # t0 = 1457954284.02856 # ualarm(100000) # t1 = 1457954284.12872 # dt = 0.100162982940674 # r = 1.00162982940674 ok 4 # t0 = 1457954284.12919 # ualarm(1100000) # t1 = 1457954285.23049 # dt = 1.10130000114441 # r = 1.00118181922219 ok 5 # t0 = 1457954285.23077 # ualarm(2200000) # t1 = 1457954287.4312 # dt = 2.2004280090332 # r = 1.00019454956055 ok 6 # t0 = 1457954287.43126 # ualarm(4300000) # t1 = 1457954291.73143 # dt = 4.30017685890198 # r = 1.0000411299772 ok 7 # t0 = 1457954291.73274 # got0 = 0 # t1 = 1457954292.03274 # t1 - t0 = 0.300000905990601 # got1 = 200002 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 23891, terminating the watchdog process 23892 before it terminates me in 351 seconds (testing took 9 seconds). # kill KILL 23892 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 23893, starting the watchdog process... # The watchdog process 23894 launched, continuing testing... # I am the watchdog process 23894, sleeping for 360 seconds... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 23893, terminating the watchdog process 23894 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 23894 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.11 usr 0.02 sys + 14.64 cusr 0.15 csys = 14.92 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 /data/fly2000/cpanfly-5.20/var/cpan/build/Time-HiRes-1.9732-ZE0AtZ && tar cvf - Time-HiRes-1.9732.ppd blib) | gzip -c >/data/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI/Time-HiRes-1.9732.tar.gz Time-HiRes-1.9732.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 >>> mv /data/fly2000/cpanfly-5.20/var/cpan/build/Time-HiRes-1.9732-ZE0AtZ/Time-HiRes-1.9732.ppd /data/fly2000/cpanfly-5.20/var/REPO/J/JH/JHI VmSize: 326312 kB Finished 2016-03-14T04:18:16