Re: [RELEASE CANDIDATE] Apache-Test-1.40 RC1

I have just tried to build mod_perl from mod_perl-SVN as at Sun Aug 28 03:25:32 UTC 2016, "make test" hang at:

t/protocol/eliza.t ...................... 1/7 

Further testing: 


waiting 300 seconds for server to start: .[Sun Aug 28 13:49:17.900567 2016] [env:warn] [pid 18374:tid 140032060188416] AH01506: PassEnv variable LD_LIBRARY_PATH was undefined
[Sun Aug 28 13:49:17.927443 2016] [perl:info] [pid 18374:tid 140032060188416] 6 Apache2:: modules loaded
[Sun Aug 28 13:49:17.927468 2016] [perl:info] [pid 18374:tid 140032060188416] 0 APR:: modules loaded
[Sun Aug 28 13:49:17.927506 2016] [perl:info] [pid 18374:tid 140032060188416] base server + 32 vhosts ready to run tests
..
waiting 300 seconds for server to start: ok (waited 2 secs)
server localhost:8529 started
server localhost:8530 listening (filter_out_apache)
server localhost:8531 listening (perlsections)
server localhost:8532 listening (inherit)
server localhost:8533 listening (TestModperl::perl_options2)
server localhost:8534 listening (TestModperl::perl_options)
server localhost:8535 listening (TestModperl::setupenv)
server localhost:8536 listening (TestModperl::merge)
server localhost:8537 listening (TestUser::rewrite)
server localhost:8538 listening (TestModules::proxy)
server localhost:8539 listening (TestVhost::config)
server localhost:8540 listening (TestVhost::log)
server localhost:8541 listening (TestDirective::perlcleanuphandler)
server localhost:8542 listening (TestProtocol::eliza)
server localhost:8543 listening (TestProtocol::echo_nonblock)
server localhost:8544 listening (TestProtocol::echo_block)
server localhost:8545 listening (TestProtocol::echo_timeout)
server localhost:8546 listening (TestProtocol::echo_bbs2)
server localhost:8547 listening (TestProtocol::pseudo_http)
server localhost:8548 listening (TestProtocol::echo_filter)
server localhost:8549 listening (TestProtocol::echo_bbs)
server localhost:8550 listening (TestPreConnection::note)
server localhost:8551 listening (TestHooks::startup)
server localhost:8552 listening (TestHooks::hookrun)
server localhost:8553 listening (TestHooks::trans)
server localhost:8554 listening (TestHooks::stacked_handlers2)
server localhost:8555 listening (TestHooks::init)
server localhost:8556 listening (TestFilter::both_str_con_add)
server localhost:8557 listening (TestFilter::in_bbs_inject_header)
server localhost:8558 listening (TestFilter::in_str_msg)
server localhost:8559 listening (TestFilter::in_bbs_msg)
server localhost:8560 listening (TestDirective::perlrequire)
server localhost:8561 listening (TestDirective::perlmodule)
server localhost:8562 listening (TestPerl::ithreads)
server localhost:8563 listening (TestPerl::ithreads3)
server localhost:8564 listening (TestDirective::perlloadmodule3)
server localhost:8565 listening (TestDirective::perlloadmodule5)
server localhost:8566 listening (TestDirective::perlloadmodule4)
server localhost:8567 listening (TestAPI::add_config)
server localhost:8568 listening (TestDirective::perlloadmodule6)
server localhost:8569 listening (TestHooks::push_handlers_anon)
t/protocol/eliza.t .. 
1..7
# Running under perl version 5.020002 for linux
# Current time local: Sun Aug 28 13:49:20 2016
# Current time GMT:   Sun Aug 28 03:49:20 2016
# Using Test.pm version 1.26
# Using Apache/Test.pm version 1.39
ok 1
# send: Hello Eliza
# recv: Hi. What seems to be your problem?
ok 2
# send: How are you?
# recv: Why do you ask?
ok 3
# send: Why do I have core dumped?
# recv: Does that question interest you?
ok 4
# send: I feel like writing some tests today, what about you?
# recv: Please go on.
ok 5
# send: Good bye, Eliza
# recv: Tell me more about that.
ok 6

(test hang.)

From error_log:

> less t/logs/error_log 
********************************************************************************
*** This is a test for Apache2::ServerUtil::server_shutdown_cleanup_register ***
*** Following a line consisting only of * characters there should be a line  ***
*** containing                                                               ***
***     "cleanup died: testing server_shutdown_cleanup_register".            ***
*** The next line should then read                                           ***
***     "done with server_shutdown_cleanup_register"                         ***
********************************************************************************
Apache2::ServerUtil: cleanup died: testing server_shutdown_cleanup_register
*** done with server_shutdown_cleanup_register                               ***
********************************************************************************
END in modperl_extra.pl, pid=18374
END in modperl_extra.pl, pid=18374
[Sun Aug 28 13:49:19.664072 2016] [mpm_event:notice] [pid 18378:tid 140032060188416] AH00489: Apache/2.4.23 (Unix) world domination series/2.0 mod_perl/2.0.10-dev Perl/v5.20.2 configured -- resuming normal operations
[Sun Aug 28 13:49:19.664099 2016] [mpm_event:info] [pid 18378:tid 140032060188416] AH00490: Server built: Aug 25 2016 18:42:20
[Sun Aug 28 13:49:19.664120 2016] [core:notice] [pid 18378:tid 140032060188416] AH00094: Command line: '/usr/local/httpd-2.4.23_test/bin/httpd -d /usr/local/src/mod_perl-2.0/t -f /usr/local/src/mod_perl-2.0/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS'
[Sun Aug 28 13:49:20.310709 2016] [authz_core:debug] [pid 18383:tid 140031801497344] mod_authz_core.c(835): [client 127.0.0.1:49536] AH01628: authorization result: granted (no directives)
[Sun Aug 28 13:50:39.153071 2016] [perl:info] [pid 18384:tid 140032060188416] Child process pid=18384 is exiting
[Sun Aug 28 13:50:39.153231 2016] [perl:info] [pid 18384:tid 140032060188416] Child process pid=18384 is exiting - server push
END in modperl_extra.pl, pid=18384
[Sun Aug 28 13:50:39.154133 2016] [perl:info] [pid 18383:tid 140032060188416] Child process pid=18383 is exiting
[Sun Aug 28 13:50:39.154204 2016] [perl:info] [pid 18383:tid 140032060188416] Child process pid=18383 is exiting - server push
END in modperl_extra.pl, pid=18383
[Sun Aug 28 13:50:39.156070 2016] [perl:info] [pid 18382:tid 140032060188416] Child process pid=18382 is exiting
[Sun Aug 28 13:50:39.156161 2016] [perl:info] [pid 18382:tid 140032060188416] Child process pid=18382 is exiting - server push
END in modperl_extra.pl, pid=18382
[Sun Aug 28 13:50:39.158061 2016] [perl:info] [pid 18474:tid 140032060188416] Child process pid=18474 is exiting
[Sun Aug 28 13:50:39.158147 2016] [perl:info] [pid 18474:tid 140032060188416] Child process pid=18474 is exiting - server push
END in modperl_extra.pl, pid=18474
END in modperl_extra.pl, pid=18383
END in modperl_extra.pl, pid=18474
END in modperl_extra.pl, pid=18384
END in modperl_extra.pl, pid=18382
[Sun Aug 28 13:50:41.496956 2016] [core:info] [pid 18378:tid 140032060188416] AH00096: removed PID file /usr/local/src/mod_perl-2.0/t/logs/httpd.pid (pid=18378)
[Sun Aug 28 13:50:41.496980 2016] [mpm_event:notice] [pid 18378:tid 140032060188416] AH00491: caught SIGTERM, shutting down
********************************************************************************
*** This is a test for Apache2::ServerUtil::server_shutdown_cleanup_register ***
*** Following a line consisting only of * characters there should be a line  ***
*** containing                                                               ***
***     "cleanup died: testing server_shutdown_cleanup_register".            ***
*** The next line should then read                                           ***
***     "done with server_shutdown_cleanup_register"                         ***
********************************************************************************
Apache2::ServerUtil: cleanup died: testing server_shutdown_cleanup_register
*** done with server_shutdown_cleanup_register                               ***
********************************************************************************
END in modperl_extra.pl, pid=18378
END in modperl_extra.pl, pid=18378

> t/REPORT
-------------8<---------- Start Bug Report ------------8<----------
1. Problem Description:

  [DESCRIBE THE PROBLEM HERE]

2. Used Components and their Configuration:

*** mod_perl version 2.000010

*** using /usr/local/src/mod_perl-2.0/lib/Apache2/BuildConfig.pm

*** Makefile.PL options:
  MP_APR_CONFIG  => /usr/local/httpd-2.4.23_test/bin/apr-1-config
  MP_APR_LIB     => aprext
  MP_APXS        => /usr/local/httpd-2.4.23_test/bin/apxs
  MP_COMPAT_1X   => 1
  MP_GENERATE_XS => 1
  MP_LIBNAME     => mod_perl
  MP_USE_DSO     => 1


*** /usr/local/httpd-2.4.23_test/bin/httpd -V
Server version: Apache/2.4.23 (Unix)
Server built:   Aug 25 2016 18:42:20
Server's Module Magic Number: 20120211:61
Server loaded:  APR 1.5.1, APR-UTIL 1.5.4
Compiled using: APR 1.5.1, APR-UTIL 1.5.4
Architecture:   64-bit
Server MPM:     event
  threaded:     yes (fixed thread count)
    forked:     yes (variable process count)
Server compiled with....
 -D APR_HAS_SENDFILE
 -D APR_HAS_MMAP
 -D APR_HAVE_IPV6 (IPv4-mapped addresses enabled)
 -D APR_USE_SYSVSEM_SERIALIZE
 -D APR_USE_PTHREAD_SERIALIZE
 -D SINGLE_LISTEN_UNSERIALIZED_ACCEPT
 -D APR_HAS_OTHER_CHILD
 -D AP_HAVE_RELIABLE_PIPED_LOGS
 -D DYNAMIC_MODULE_LIMIT=256
 -D HTTPD_ROOT="/usr/local/httpd-2.4.23_test"
 -D SUEXEC_BIN="/usr/local/httpd-2.4.23_test/bin/suexec"
 -D DEFAULT_PIDLOG="logs/httpd.pid"
 -D DEFAULT_SCOREBOARD="logs/apache_runtime_status"
 -D DEFAULT_ERRORLOG="logs/error_log"
 -D AP_TYPES_CONFIG_FILE="conf/mime.types"
 -D SERVER_CONFIG_FILE="conf/httpd.conf"

*** /usr/bin/ldd /usr/local/httpd-2.4.23_test/bin/httpd
        linux-vdso.so.1 =>  (0x00007ffc3d1fd000)
        libpcre.so.0 => /lib64/libpcre.so.0 (0x00000033e8800000)
        libaprutil-1.so.0 => /usr/local/apr/lib/libaprutil-1.so.0 (0x00007fbdb4bb9000)
        libdb-4.7.so => /lib64/libdb-4.7.so (0x00000033f8000000)
        libexpat.so.1 => /lib64/libexpat.so.1 (0x00000033eb000000)
        libapr-1.so.0 => /usr/local/apr/lib/libapr-1.so.0 (0x00007fbdb4983000)
        librt.so.1 => /lib64/librt.so.1 (0x00000033e6c00000)
        libcrypt.so.1 => /lib64/libcrypt.so.1 (0x0000003ff3200000)
        libpthread.so.0 => /lib64/libpthread.so.0 (0x00000033e6400000)
        libc.so.6 => /lib64/libc.so.6 (0x00000033e6000000)
        /lib64/ld-linux-x86-64.so.2 (0x00000033e5c00000)
        libfreebl3.so => /lib64/libfreebl3.so (0x0000003ff2e00000)
        libdl.so.2 => /lib64/libdl.so.2 (0x00000033e6800000)


*** (apr|apu)-config linking info

 -L/usr/local/apr/lib -laprutil-1  -ldb-4.7   -lexpat -L/usr/include/lib
 -L/usr/local/apr/lib -lapr-1 -lrt -lcrypt  -lpthread 



*** /usr/local/bin/perl -V
Summary of my perl5 (revision 5 version 20 subversion 2) configuration:
   
  Platform:
    osname=linux, osvers=2.6.32-504.12.2.el6.x86_64, archname=x86_64-linux-thread-multi
    uname='linux xxxx.xxx.xxxx.xxx.xx 2.6.32-504.12.2.el6.x86_64 #1 smp sun feb 1 12:14:02 est 2015 x86_64 x86_64 x86_64 gnulinux '
    config_args='-Dcc=gcc -Dusethreads -Dprefix=/usr/local -des -A ccflags=-fPIC'
    hint=recommended, useposix=true, d_sigaction=define
    useithreads=define, usemultiplicity=define
    use64bitint=define, use64bitall=define, uselongdouble=undef
    usemymalloc=n, bincompat5005=undef
  Compiler:
    cc='gcc', ccflags ='-D_REENTRANT -D_GNU_SOURCE -fPIC -fwrapv -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64',
    optimize='-O2',
    cppflags='-D_REENTRANT -D_GNU_SOURCE -fPIC -fwrapv -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include'
    ccversion='', gccversion='4.4.7 20120313 (Red Hat 4.4.7-11)', gccosandvers=''
    intsize=4, longsize=8, ptrsize=8, doublesize=8, byteorder=12345678
    d_longlong=define, longlongsize=8, d_longdbl=define, longdblsize=16
    ivtype='long', ivsize=8, nvtype='double', nvsize=8, Off_t='off_t', lseeksize=8
    alignbytes=8, prototype=define
  Linker and Libraries:
    ld='gcc', ldflags =' -fstack-protector -L/usr/local/lib'
    libpth=/usr/local/lib /usr/lib /lib/../lib64 /usr/lib/../lib64 /lib /lib64 /usr/lib64 /usr/local/lib64
    libs=-lnsl -lgdbm -ldb -ldl -lm -lcrypt -lutil -lpthread -lc
    perllibs=-lnsl -ldl -lm -lcrypt -lutil -lpthread -lc
    libc=libc-2.12.so, so=so, useshrplib=false, libperl=libperl.a
    gnulibc_version='2.12'
  Dynamic Linking:
    dlsrc=dl_dlopen.xs, dlext=so, d_dlsymun=undef, ccdlflags='-Wl,-E'
    cccdlflags='-fPIC', lddlflags='-shared -O2 -L/usr/local/lib -fstack-protector'


Characteristics of this binary (from libperl): 
  Compile-time options: HAS_TIMES MULTIPLICITY PERLIO_LAYERS
                        PERL_DONT_CREATE_GVSV
                        PERL_HASH_FUNC_ONE_AT_A_TIME_HARD
                        PERL_IMPLICIT_CONTEXT PERL_MALLOC_WRAP
                        PERL_NEW_COPY_ON_WRITE PERL_PRESERVE_IVUV
                        USE_64_BIT_ALL USE_64_BIT_INT USE_ITHREADS
                        USE_LARGE_FILES USE_LOCALE USE_LOCALE_COLLATE
                        USE_LOCALE_CTYPE USE_LOCALE_NUMERIC USE_PERLIO
                        USE_PERL_ATOF USE_REENTRANT_API
  Built under linux
  Compiled at Mar 27 2015 14:50:02
  %ENV:
    PERL_LWP_USE_HTTP_10="1"
  @INC:
    /usr/local/lib/perl5/site_perl/5.20.2/x86_64-linux-thread-multi
    /usr/local/lib/perl5/site_perl/5.20.2
    /usr/local/lib/perl5/5.20.2/x86_64-linux-thread-multi
    /usr/local/lib/perl5/5.20.2
    /usr/local/lib/perl5/site_perl/5.18.2
    /usr/local/lib/perl5/site_perl/5.18.0
    /usr/local/lib/perl5/site_perl
    .

*** Packages of interest status:

Apache2            : -
Apache2::Request   : -
CGI                : 3.65
ExtUtils::MakeMaker: 6.98
LWP                : 5.837
mod_perl           : -
mod_perl2          : 2.000010


3. This is the core dump trace: (if you get a core dump):

  [CORE TRACE COMES HERE]

This report was generated by t/REPORT on Sun Aug 28 03:52:33 2016 GMT.

-------------8<---------- End Bug Report --------------8<----------



Regards,



Jie