PATH=/usr/bin:/bin:/home/fly1800/cpanfly-5.18/var/megalib/bin Start 2016-04-24T08:12:28 ActivePerl-1800 CPAN-2.10 Reading '/home/fly1800/cpanfly-5.18/var/cpan/Metadata' Database was generated on Sun, 24 Apr 2016 04:17:02 GMT Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/J/JH/JHI/CHECKSUMS Checksum for /home/fly1800/cpanfly-5.18/var/cpan/sources/authors/id/J/JH/JHI/Time-HiRes-1.9733.tar.gz ok Time-HiRes-1.9733/ Time-HiRes-1.9733/Changes Time-HiRes-1.9733/fallback/ Time-HiRes-1.9733/hints/ Time-HiRes-1.9733/HiRes.pm Time-HiRes-1.9733/HiRes.xs Time-HiRes-1.9733/Makefile.PL Time-HiRes-1.9733/MANIFEST Time-HiRes-1.9733/META.json Time-HiRes-1.9733/META.yml Time-HiRes-1.9733/ppport.h Time-HiRes-1.9733/README Time-HiRes-1.9733/t/ Time-HiRes-1.9733/TODO Time-HiRes-1.9733/typemap Time-HiRes-1.9733/t/alarm.t Time-HiRes-1.9733/t/clock.t Time-HiRes-1.9733/t/gettimeofday.t Time-HiRes-1.9733/t/itimer.t Time-HiRes-1.9733/t/nanosleep.t Time-HiRes-1.9733/t/sleep.t Time-HiRes-1.9733/t/stat.t Time-HiRes-1.9733/t/time.t Time-HiRes-1.9733/t/tv_interval.t Time-HiRes-1.9733/t/ualarm.t Time-HiRes-1.9733/t/usleep.t Time-HiRes-1.9733/t/Watchdog.pm Time-HiRes-1.9733/hints/aix.pl Time-HiRes-1.9733/hints/dec_osf.pl Time-HiRes-1.9733/hints/dynixptx.pl Time-HiRes-1.9733/hints/irix.pl Time-HiRes-1.9733/hints/linux.pl Time-HiRes-1.9733/hints/sco.pl Time-HiRes-1.9733/hints/solaris.pl Time-HiRes-1.9733/hints/svr4.pl Time-HiRes-1.9733/fallback/const-c.inc Time-HiRes-1.9733/fallback/const-xs.inc /bin/tar: Read 2048 bytes from - Configuring J/JH/JHI/Time-HiRes-1.9733.tar.gz with Makefile.PL >>> /home/fly1800/ap1800-297235/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/fly1800/cpanfly-5.18/var/megalib Want /home/fly1800/ap1800-297235/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.9733.tar.gz /home/fly1800/ap1800-297235/bin/perl-static Makefile.PL -- OK Running make for J/JH/JHI/Time-HiRes-1.9733.tar.gz >>> make cp HiRes.pm blib/lib/Time/HiRes.pm Running Mkbootstrap for Time::HiRes () chmod 644 "HiRes.bs" "/home/fly1800/ap1800-297235/bin/perl-static" "/home/fly1800/cpanfly-5.18/var/megalib/ExtUtils/xsubpp" -typemap "/home/fly1800/ap1800-297235/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 -fstack-protector -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -O2 -DVERSION=\"1.9733\" -DXS_VERSION=\"1.9733\" -fPIC "-I/home/fly1800/ap1800-297235/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 -fstack-protector HiRes.o -o blib/arch/auto/Time/HiRes/HiRes.so \ -lrt \ chmod 755 blib/arch/auto/Time/HiRes/HiRes.so "/home/fly1800/ap1800-297235/bin/perl-static" -MExtUtils::Command::MM -e 'cp_nonempty' -- HiRes.bs blib/arch/auto/Time/HiRes/HiRes.bs 644 Manifying 1 pod document JHI/Time-HiRes-1.9733.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/fly1800/ap1800-297235/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 8429, starting the watchdog process... # I am the watchdog process 8430, sleeping for 360 seconds... # The watchdog process 8430 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.302153 # Select returned! 4 0.302334 # 0.899222 # Tick! 3 0.604162 # Select returned! 3 0.604323 # 0.798559 # Tick! 2 0.906174 # Select returned! 2 0.906328 # 0.697890666666667 # Tick! 1 1.208169 # Select returned! 1 1.208308 # 0.597230666666667 # Tick! 0 1.510183 # Select returned! 0 1.51033 # 0.496556666666667 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1461510757.0883, t0 = 1461510757.0883, dt = 0 # N = 2048, t1 = 1461510757.08836, t0 = 1461510757.0883, dt = 6.4849853515625e-05 # N = 4096, t1 = 1461510757.08866, t0 = 1461510757.08841, dt = 0.000251054763793945 # N = 8192, t1 = 1461510757.08896, t0 = 1461510757.08869, dt = 0.000264883041381836 # N = 16384, t1 = 1461510757.09002, t0 = 1461510757.08901, dt = 0.00101399421691895 # N = 32768, t1 = 1461510757.09225, t0 = 1461510757.09007, dt = 0.00217819213867188 # N = 65536, t1 = 1461510757.09713, t0 = 1461510757.09281, dt = 0.00431704521179199 # N = 131072, t1 = 1461510757.10638, t0 = 1461510757.09752, dt = 0.0088651180267334 # N = 262144, t1 = 1461510757.1233, t0 = 1461510757.1067, dt = 0.0166060924530029 # Alarm 1 - 1461510757.13519 # Alarm 2 - 1461510757.16908 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 8429, terminating the watchdog process 8430 before it terminates me in 354 seconds (testing took 6 seconds). # kill KILL 8430 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 8431, starting the watchdog process... # I am the watchdog process 8432, sleeping for 360 seconds... # The watchdog process 8432 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 = 1461510762.91389, t0 = 1461510761.41116 # dt = 1.50272583961487, rt = 0.00181722640991211 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.24 ok 5 # I am the main process 8431, terminating the watchdog process 8432 before it terminates me in 357 seconds (testing took 3 seconds). # kill KILL 8432 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 8433, starting the watchdog process... # I am the watchdog process 8434, sleeping for 360 seconds... # The watchdog process 8434 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 8433, terminating the watchdog process 8434 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 8434 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 8435, starting the watchdog process... # I am the watchdog process 8436, sleeping for 360 seconds... # The watchdog process 8436 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.498924 0.400939 # Tick! 2 0.505124 # Tick! 1 0.914476 # Tick! 0 1.318142 # Tick! 0 1.723873 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 8435, terminating the watchdog process 8436 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 8436 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 8437, starting the watchdog process... # I am the watchdog process 8438, sleeping for 360 seconds... # The watchdog process 8438 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 8437, terminating the watchdog process 8438 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 8438 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 8439, starting the watchdog process... # I am the watchdog process 8440, sleeping for 360 seconds... # The watchdog process 8440 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1461510768.05293 ok 3 # sleep...0.501517 ok 4 # I am the main process 8439, terminating the watchdog process 8440 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 8440 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 8441, starting the watchdog process... # I am the watchdog process 8442, sleeping for 360 seconds... # The watchdog process 8442 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 = 1461510768 1461510769 1461510769 1461510769 1461510769 # atime = 1461510768 1461510769 1461510769 1461510769 1461510770 # 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 8441, terminating the watchdog process 8442 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 8442 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 8443, starting the watchdog process... # I am the watchdog process 8444, sleeping for 360 seconds... # The watchdog process 8444 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 11.6973693370819, n = 100, s/n = 0.116973693370819 # I am the main process 8443, terminating the watchdog process 8444 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 8444 = 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 8446, starting the watchdog process... # I am the watchdog process 8447, sleeping for 360 seconds... # The watchdog process 8447 launched, continuing testing... ok 1 # tick = 1, one = 1461510770, two = 1461510770, three = 1461510770 ok 2 # tick = 3, one = 1461510770, two = 1461510770, three = 1461510770 ok 3 - negative time error # t0 = 1461510770.25624 # ualarm(100000) # t1 = 1461510770.35747 # dt = 0.101224899291992 # r = 1.01224899291992 ok 4 # t0 = 1461510770.35941 # ualarm(1100000) # t1 = 1461510771.46045 # dt = 1.10104298591614 # r = 1.00094816901467 ok 5 # t0 = 1461510771.46279 # ualarm(2200000) # t1 = 1461510773.66457 # dt = 2.20178604125977 # r = 1.00081183693626 ok 6 # t0 = 1461510773.66607 # ualarm(4300000) # t1 = 1461510777.96723 # dt = 4.30115795135498 # r = 1.00026929101279 ok 7 # t0 = 1461510777.96849 # got0 = 0 # t1 = 1461510778.2685 # t1 - t0 = 0.30000114440918 # got1 = 200003 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 8446, terminating the watchdog process 8447 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 8447 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 8448, starting the watchdog process... # I am the watchdog process 8449, sleeping for 360 seconds... # The watchdog process 8449 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 8448, terminating the watchdog process 8449 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 8449 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.06 usr 0.04 sys + 13.66 cusr 0.18 csys = 13.94 CPU) Result: PASS JHI/Time-HiRes-1.9733.tar.gz make test TEST_VERBOSE=1 -- OK Jarkko Hietaniemi <jhi@iki.fi> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1800/cpanfly-5.18/var/cpan/build/Time-HiRes-1.9733-n1NmfO && tar cvf - Time-HiRes-1.9733.ppd blib) | gzip -c >/home/fly1800/cpanfly-5.18/var/REPO/J/JH/JHI/Time-HiRes-1.9733.tar.gz Time-HiRes-1.9733.ppd blib/ 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 blib/man3/ blib/man3/Time::HiRes.3 >>> mv /home/fly1800/cpanfly-5.18/var/cpan/build/Time-HiRes-1.9733-n1NmfO/Time-HiRes-1.9733.ppd /home/fly1800/cpanfly-5.18/var/REPO/J/JH/JHI VmSize: 315888 kB Finished 2016-04-24T08:13:02