PATH=/usr/bin:/bin:/home/fly1800/var/megalib/bin Start 2015-11-14T00:06:58 ActivePerl-1800 CPAN-2.00 Reading '/home/fly1800/var/cpan/Metadata' Database was generated on Sat, 14 Nov 2015 07:06:02 GMT Running make for R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Fetching with LWP: http://cpan.nas1.activestate.com/authors/id/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Fetching with LWP: http://cpan.nas1.activestate.com/authors/id/R/RJ/RJBS/CHECKSUMS Checksum for /home/fly1800/var/cpan/sources/authors/id/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz ok Time-HiRes-1.9728/ Time-HiRes-1.9728/Changes Time-HiRes-1.9728/fallback/ Time-HiRes-1.9728/hints/ Time-HiRes-1.9728/HiRes.pm Time-HiRes-1.9728/HiRes.xs Time-HiRes-1.9728/Makefile.PL Time-HiRes-1.9728/MANIFEST Time-HiRes-1.9728/META.json Time-HiRes-1.9728/META.yml Time-HiRes-1.9728/ppport.h Time-HiRes-1.9728/README Time-HiRes-1.9728/t/ Time-HiRes-1.9728/TODO Time-HiRes-1.9728/typemap Time-HiRes-1.9728/t/alarm.t Time-HiRes-1.9728/t/clock.t Time-HiRes-1.9728/t/gettimeofday.t Time-HiRes-1.9728/t/itimer.t Time-HiRes-1.9728/t/nanosleep.t Time-HiRes-1.9728/t/sleep.t Time-HiRes-1.9728/t/stat.t Time-HiRes-1.9728/t/time.t Time-HiRes-1.9728/t/tv_interval.t Time-HiRes-1.9728/t/ualarm.t Time-HiRes-1.9728/t/usleep.t Time-HiRes-1.9728/t/Watchdog.pm Time-HiRes-1.9728/hints/aix.pl Time-HiRes-1.9728/hints/dec_osf.pl Time-HiRes-1.9728/hints/dynixptx.pl Time-HiRes-1.9728/hints/irix.pl Time-HiRes-1.9728/hints/linux.pl Time-HiRes-1.9728/hints/sco.pl Time-HiRes-1.9728/hints/solaris.pl Time-HiRes-1.9728/hints/svr4.pl Time-HiRes-1.9728/fallback/const-c.inc /bin/tar: Read 4608 bytes from - Time-HiRes-1.9728/fallback/const-xs.inc CPAN.pm: Building R/RJ/RJBS/Time-HiRes-1.9728.tar.gz >>> /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 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'. >>> 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/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.9728\" -DXS_VERSION=\"1.9728\" -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 RJBS/Time-HiRes-1.9728.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 16582, starting the watchdog process... # I am the watchdog process 16583, sleeping for 360 seconds... # The watchdog process 16583 launched, continuing testing... ok 1 - require Time::HiRes; # sigaction tick, ALRM = 14 # Tick! 4 0.300505 # Select returned! 4 0.300758 # 0.899747333333333 # Tick! 3 0.601575 # Select returned! 3 0.601789 # 0.799403666666667 # Tick! 2 0.90267 # Select returned! 2 0.902855 # 0.699048333333333 # Tick! 1 1.203696 # Select returned! 1 1.203863 # 0.598712333333333 # Tick! 0 1.504799 # Select returned! 0 1.505 # 0.498333333333333 ok 2 # 0 ok 3 - negative time error # Finding delay loop... # N = 1024, t1 = 1447488427.61276, t0 = 1447488427.61268, dt = 7.20024108886719e-05 # N = 2048, t1 = 1447488427.61314, t0 = 1447488427.61283, dt = 0.000304937362670898 # N = 4096, t1 = 1447488427.61437, t0 = 1447488427.61322, dt = 0.00114583969116211 # N = 8192, t1 = 1447488427.61502, t0 = 1447488427.61444, dt = 0.000583171844482422 # N = 16384, t1 = 1447488427.6163, t0 = 1447488427.61514, dt = 0.00116181373596191 # N = 32768, t1 = 1447488427.61775, t0 = 1447488427.61639, dt = 0.00136017799377441 # N = 65536, t1 = 1447488427.62278, t0 = 1447488427.61787, dt = 0.00490689277648926 # N = 131072, t1 = 1447488427.63167, t0 = 1447488427.62305, dt = 0.00862503051757812 # N = 262144, t1 = 1447488427.64916, t0 = 1447488427.63168, dt = 0.0174789428710938 # Alarm 1 - 1447488427.66086 # Alarm 2 - 1447488427.69525 ok 4 ok 5 ok 6 ok 7 ok 8 ok 9 ok 10 # I am the main process 16582, terminating the watchdog process 16583 before it terminates me in 355 seconds (testing took 5 seconds). # kill KILL 16583 = 1 # All done. ok t/clock.t ......... 1..5 # I am the main process 16584, starting the watchdog process... # I am the watchdog process 16585, sleeping for 360 seconds... # The watchdog process 16585 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 = 1447488433.45584, t0 = 1447488431.95388 # dt = 1.50196099281311, rt = 0.00130732854207349 ok 2 ok 3 ok 4 # clock = 0.03 # clock = 0.03 0.1 # clock = 0.03 0.1 0.18 # clock = 0.03 0.1 0.18 0.24 ok 5 # I am the main process 16584, terminating the watchdog process 16585 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 16585 = 1 # All done. ok t/gettimeofday.t .. 1..6 # I am the main process 16586, starting the watchdog process... # I am the watchdog process 16587, sleeping for 360 seconds... # The watchdog process 16587 launched, continuing testing... # gettimeofday returned 2 args ok 1 ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 16586, terminating the watchdog process 16587 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 16587 = 1 # All done. ok t/itimer.t ........ 1..2 # I am the main process 16588, starting the watchdog process... # I am the watchdog process 16589, sleeping for 360 seconds... # The watchdog process 16589 launched, continuing testing... # setitimer: 0 0 ok 1 - ITIMER_VIRTUAL defined with sufficient granularity # getitimer: 0.499924 0.400939 # Tick! 2 0.509595 # Tick! 1 0.919082 # Tick! 0 1.325953 # Tick! 0 1.729075 # getitimer: 0 0 # at end, i=0 ok 2 - time left should be zero # I am the main process 16588, terminating the watchdog process 16589 before it terminates me in 358 seconds (testing took 2 seconds). # kill KILL 16589 = 1 # All done. ok t/nanosleep.t ..... 1..3 # I am the main process 16590, starting the watchdog process... # I am the watchdog process 16591, sleeping for 360 seconds... # The watchdog process 16591 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 # I am the main process 16590, terminating the watchdog process 16591 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 16591 = 1 # All done. ok t/sleep.t ......... 1..4 # I am the main process 16592, starting the watchdog process... # I am the watchdog process 16593, sleeping for 360 seconds... # The watchdog process 16593 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 - negative time error # time...1447488438.64235 ok 3 # sleep...0.501062 ok 4 # I am the main process 16592, terminating the watchdog process 16593 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 16593 = 1 # All done. ok t/stat.t .......... 1..43 # I am the main process 16594, starting the watchdog process... # I am the watchdog process 16595, sleeping for 360 seconds... # The watchdog process 16595 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 = 1447488439 1447488439 1447488439 1447488440 1447488440 # atime = 1447488439 1447488439 1447488440 1447488440 1447488440 # 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 16594, terminating the watchdog process 16595 before it terminates me in 359 seconds (testing took 1 seconds). # kill KILL 16595 = 1 # All done. ok t/time.t .......... 1..2 # I am the main process 16596, starting the watchdog process... # I am the watchdog process 16597, sleeping for 360 seconds... # The watchdog process 16597 launched, continuing testing... ok 1 - require Time::HiRes; ok 2 # s = 92.560186624527, n = 100, s/n = 0.92560186624527 # I am the main process 16596, terminating the watchdog process 16597 before it terminates me in 360 seconds (testing took 0 seconds). # kill KILL 16597 = 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 16599, starting the watchdog process... # I am the watchdog process 16600, sleeping for 360 seconds... # The watchdog process 16600 launched, continuing testing... ok 1 # tick = 1, one = 1447488441, two = 1447488441, three = 1447488441 ok 2 # tick = 3, one = 1447488441, two = 1447488441, three = 1447488441 ok 3 - negative time error # t0 = 1447488441.08144 # ualarm(100000) # t1 = 1447488441.18418 # dt = 0.102742910385132 # r = 1.02742910385132 ok 4 # t0 = 1447488441.18602 # ualarm(1100000) # t1 = 1447488442.28729 # dt = 1.10126805305481 # r = 1.00115277550437 ok 5 # t0 = 1447488442.28893 # ualarm(2200000) # t1 = 1447488444.48982 # dt = 2.20088696479797 # r = 1.00040316581726 ok 6 # t0 = 1447488444.49142 # ualarm(4300000) # t1 = 1447488448.79388 # dt = 4.30246496200562 # r = 1.00057324697805 ok 7 # t0 = 1447488448.79536 # got0 = 0 # t1 = 1447488449.09536 # t1 - t0 = 0.300001859664917 # got1 = 200007 ok 8 ok 9 ok 10 ok 11 ok 12 # I am the main process 16599, terminating the watchdog process 16600 before it terminates me in 352 seconds (testing took 8 seconds). # kill KILL 16600 = 1 # All done. ok t/usleep.t ........ 1..6 # I am the main process 16601, starting the watchdog process... # I am the watchdog process 16602, sleeping for 360 seconds... # The watchdog process 16602 launched, continuing testing... ok 1 - negative time error ok 2 ok 3 ok 4 ok 5 ok 6 # I am the main process 16601, terminating the watchdog process 16602 before it terminates me in 356 seconds (testing took 4 seconds). # kill KILL 16602 = 1 # All done. ok All tests successful. Files=11, Tests=95, 27 wallclock secs ( 0.08 usr 0.05 sys + 14.33 cusr 0.17 csys = 14.63 CPU) Result: PASS RJBS/Time-HiRes-1.9728.tar.gz make test TEST_VERBOSE=1 -- OK Ricardo SIGNES <rjbs@cpan.org> High resolution alarm, sleep, gettimeofday, interval timers >>> (cd /home/fly1800/var/cpan/build/Time-HiRes-1.9728-khRyHp && tar cvf - Time-HiRes-1.9728.ppd blib) | gzip -c >/home/fly1800/var/REPO/R/RJ/RJBS/Time-HiRes-1.9728.tar.gz Time-HiRes-1.9728.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/var/cpan/build/Time-HiRes-1.9728-khRyHp/Time-HiRes-1.9728.ppd /home/fly1800/var/REPO/R/RJ/RJBS VmSize: 310272 kB Finished 2015-11-14T00:07:33