Mailing List Archive

mod_perl 2.0.9: "make test" hangs with httpd v2.4.17.
---Start: Bug Report---
Issue: mod_perl 2.0.9 with httpd v2.4.17: "make test" hang @ "t/protocol/echo_block.t" with error "APR::Socket::recv: (9) Bad file descriptor at /usr/local/src/mod_perl-2.0.9/t/protocol/TestProtocol/echo_block.pm line 37".

> uname -a
Linux xxxx.xxxx.xxx.xx 2.6.32-573.7.1.el6.x86_64 #1 SMP Thu Sep 10 13:42:16 EDT 2015 x86_64 x86_64 x86_64 GNU/Linux

> lsb_release -a
LSB Version: :base-4.0-amd64:base-4.0-noarch:core-4.0-amd64:core-4.0-noarch:graphics-4.0-amd64:graphics-4.0-noarch:printing-4.0-amd64:printing-4.0-noarch
Distributor ID: RedHatEnterpriseServer
Description: Red Hat Enterprise Linux Server release 6.7 (Santiago)
Release: 6.7
Codename: Santiago

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

[DESCRIBE THE PROBLEM HERE]

2. Used Components and their Configuration:

*** mod_perl version 2.000009

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

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


*** /usr/local/httpd-2.4.17/bin/httpd -V
Server version: Apache/2.4.17 (Unix)
Server built: Oct 15 2015 10:56:48
Server's Module Magic Number: 20120211:51
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: worker
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.17"
-D SUEXEC_BIN="/usr/local/httpd-2.4.17/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.17/bin/httpd
linux-vdso.so.1 => (0x00007ffea5fee000)
libpcre.so.0 => /lib64/libpcre.so.0 (0x0000003293e00000)
libaprutil-1.so.0 => /usr/local/apr/lib/libaprutil-1.so.0 (0x00007f0708c14000)
libdb-4.7.so => /lib64/libdb-4.7.so (0x00000032a2a00000)
libexpat.so.1 => /lib64/libexpat.so.1 (0x0000003295e00000)
libapr-1.so.0 => /usr/local/apr/lib/libapr-1.so.0 (0x00007f07089de000)
librt.so.1 => /lib64/librt.so.1 (0x0000003292200000)
libcrypt.so.1 => /lib64/libcrypt.so.1 (0x0000003296600000)
libpthread.so.0 => /lib64/libpthread.so.0 (0x0000003291a00000)
libc.so.6 => /lib64/libc.so.6 (0x0000003291600000)
/lib64/ld-linux-x86-64.so.2 (0x0000003291200000)
libfreebl3.so => /lib64/libfreebl3.so (0x0000003295a00000)
libdl.so.2 => /lib64/libdl.so.2 (0x0000003291e00000)


*** (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.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.000009


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 Thu Oct 15 02:10:47 2015 GMT.

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

> make test
cd "src/modules/perl" && make
make[1]: Entering directory `/usr/local/src/mod_perl-2.0.9/src/modules/perl'
make[1]: Nothing to be done for `all'.
make[1]: Leaving directory `/usr/local/src/mod_perl-2.0.9/src/modules/perl'
...
make[1]: Leaving directory `/usr/local/src/mod_perl-2.0.9/xs'
/usr/local/bin/perl -Iblib/arch -Iblib/lib \
t/TEST -clean
[warning] setting ulimit to allow core files
ulimit -c unlimited; /usr/local/bin/perl /usr/local/src/mod_perl-2.0.9/t/TEST -clean
APACHE_TEST_APXS= APACHE_TEST_GROUP= APACHE_TEST_HTTPD= APACHE_TEST_PORT= APACHE_TEST_USER= \
/usr/local/bin/perl -Iblib/arch -Iblib/lib \
t/TEST -bugreport -verbose=0
[warning] setting ulimit to allow core files
ulimit -c unlimited; /usr/local/bin/perl /usr/local/src/mod_perl-2.0.9/t/TEST -bugreport -verbose=0
/usr/local/httpd-2.4.17/bin/httpd -d /usr/local/src/mod_perl-2.0.9/t -f /usr/local/src/mod_perl-2.0.9/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS
using Apache/2.4.17 (worker MPM)

waiting 300 seconds for server to start: .[Thu Oct 15 12:58:28.819363 2015] [env:warn] [pid 17078:tid 140304576116480] AH01506: PassEnv variable LD_LIBRARY_PATH was undefined
[Thu Oct 15 12:58:28.881076 2015] [perl:info] [pid 17078:tid 140304576116480] 6 Apache2:: modules loaded
[Thu Oct 15 12:58:28.881161 2015] [perl:info] [pid 17078:tid 140304576116480] 0 APR:: modules loaded
[Thu Oct 15 12:58:28.881224 2015] [perl:info] [pid 17078:tid 140304576116480] base server + 29 vhosts ready to run tests
....
waiting 300 seconds for server to start: ok (waited 3 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::echo_nonblock)
server localhost:8543 listening (TestProtocol::echo_block)
server localhost:8544 listening (TestProtocol::echo_timeout)
server localhost:8545 listening (TestProtocol::echo_bbs2)
server localhost:8546 listening (TestProtocol::pseudo_http)
server localhost:8547 listening (TestProtocol::echo_filter)
server localhost:8548 listening (TestProtocol::echo_bbs)
server localhost:8549 listening (TestPreConnection::note)
server localhost:8550 listening (TestHooks::startup)
server localhost:8551 listening (TestHooks::hookrun)
server localhost:8552 listening (TestHooks::trans)
server localhost:8553 listening (TestHooks::stacked_handlers2)
server localhost:8554 listening (TestHooks::init)
server localhost:8555 listening (TestFilter::both_str_con_add)
server localhost:8556 listening (TestFilter::in_bbs_inject_header)
server localhost:8557 listening (TestFilter::in_str_msg)
server localhost:8558 listening (TestFilter::in_bbs_msg)
server localhost:8559 listening (TestDirective::perlrequire)
server localhost:8560 listening (TestDirective::perlmodule)
server localhost:8561 listening (TestDirective::perlloadmodule3)
server localhost:8562 listening (TestDirective::perlloadmodule5)
server localhost:8563 listening (TestDirective::perlloadmodule4)
server localhost:8564 listening (TestAPI::add_config)
server localhost:8565 listening (TestDirective::perlloadmodule6)
server localhost:8566 listening (TestHooks::push_handlers_anon)
t/apache/add_config.t ................... ok
t/apache/cgihandler.t ................... ok
t/apache/conftree.t ..................... ok
t/apache/constants.t .................... ok
t/apache/content_length_header.t ........ ok
t/apache/daemon.t ....................... ok
t/apache/discard_rbody.t ................ ok
t/apache/post.t ......................... ok
t/apache/read.t ......................... ok
t/apache/read2.t ........................ ok
t/apache/read3.t ........................ ok
t/apache/read4.t ........................ ok
t/apache/scanhdrs.t ..................... ok
t/apache/scanhdrs2.t .................... ok
t/apache/send_cgi_header.t .............. ok
t/apache/subprocess.t ................... ok
t/apache/util.t ......................... ok
t/apache/write.t ........................ ok
t/api/access.t .......................... ok
t/api/access2.t ......................... skipped: mod_perl is not compiled with httpd-2.2
t/api/access2_24.t ...................... ok
t/api/add_config.t ...................... ok
t/api/aplog.t ........................... ok
t/api/command.t ......................... ok
t/api/conn_rec.t ........................ ok
t/api/conn_util.t ....................... ok
t/api/content_encoding.t ................ ok
t/api/custom_response.t ................. ok
t/api/err_headers_out.t ................. ok
t/api/in_out_filters.t .................. ok
t/api/internal_redirect.t ............... ok
t/api/internal_redirect_handler.t ....... ok
t/api/lookup_misc.t ..................... ok
t/api/lookup_uri.t ...................... ok
t/api/lookup_uri2.t ..................... ok
t/api/module.t .......................... ok
t/api/process.t ......................... ok
t/api/query.t ........................... ok
t/api/request_rec.t ..................... ok
t/api/request_subclass.t ................ ok
t/api/request_util.t .................... ok
t/api/response.t ........................ ok
t/api/rflush.t .......................... ok
t/api/sendfile.t ........................ ok
t/api/server_const.t .................... ok
t/api/server_rec.t ...................... ok
t/api/server_util.t ..................... ok
t/api/show.t ............................ ok
t/api/slurp_filename.t .................. ok
t/api/status.t .......................... ok
t/api/sub_request.t ..................... ok
t/api/uri.t ............................. ok
t/apr-ext/base64.t ...................... ok
t/apr-ext/brigade.t ..................... ok
t/apr-ext/bucket.t ...................... ok
t/apr-ext/date.t ........................ ok
t/apr-ext/error.t ....................... ok
t/apr-ext/finfo.t ....................... ok
t/apr-ext/os.t .......................... ok
t/apr-ext/pool.t ........................ ok
t/apr-ext/status.t ...................... ok
t/apr-ext/string.t ...................... ok
t/apr-ext/table.t ....................... ok
t/apr-ext/threadmutex.t ................. ok
t/apr-ext/threadrwlock.t ................ ok
t/apr-ext/uri.t ......................... ok
t/apr-ext/util.t ........................ ok
t/apr-ext/uuid.t ........................ ok
t/apr/base64.t .......................... ok
t/apr/brigade.t ......................... ok
t/apr/bucket.t .......................... ok
t/apr/constants.t ....................... ok
t/apr/date.t ............................ ok
t/apr/finfo.t ........................... ok
t/apr/flatten.t ......................... ok
t/apr/ipsubnet.t ........................ ok
t/apr/os.t .............................. ok
t/apr/perlio.t .......................... ok
t/apr/pool.t ............................ ok
t/apr/pool_lifetime.t ................... ok
t/apr/sockaddr.t ........................ ok
t/apr/socket.t .......................... ok
t/apr/status.t .......................... ok
t/apr/string.t .......................... ok
t/apr/table.t ........................... ok
t/apr/threadmutex.t ..................... ok
t/apr/threadrwlock.t .................... ok
t/apr/uri.t ............................. ok
t/apr/util.t ............................ ok
t/apr/uuid.t ............................ ok
t/compat/apache.t ....................... ok
t/compat/apache_file.t .................. ok
t/compat/apache_module.t ................ ok
t/compat/apache_table.t ................. ok
t/compat/apache_uri.t ................... ok
t/compat/apache_util.t .................. ok
t/compat/conn_authen.t .................. ok
t/compat/conn_rec.t ..................... ok
t/compat/request.t ...................... ok
t/compat/request_body.t ................. ok
t/compat/send_fd.t ...................... ok
t/directive/cmdparms.t .................. ok
t/directive/env.t ....................... ok
t/directive/perl.t ...................... ok
t/directive/perlcleanuphandler.t ........ ok
t/directive/perldo.t .................... ok
t/directive/perlloadmodule.t ............ ok
t/directive/perlloadmodule2.t ........... ok
t/directive/perlloadmodule3.t ........... ok
t/directive/perlloadmodule4.t ........... ok
t/directive/perlloadmodule5.t ........... ok
t/directive/perlloadmodule6.t ........... ok
t/directive/perlloadmodule7.t ........... ok
t/directive/perlmodule.t ................ ok
t/directive/perlrequire.t ............... ok
t/directive/pod.t ....................... ok
t/directive/setupenv.t .................. ok
t/error/api.t ........................... ok
t/error/runtime.t ....................... ok
t/error/syntax.t ........................ ok
t/filter/both_str_con_add.t ............. ok
t/filter/both_str_native_remove.t ....... ok
t/filter/both_str_req_add.t ............. ok
t/filter/both_str_req_mix.t ............. ok
t/filter/both_str_req_proxy.t ........... ok
t/filter/in_autoload.t .................. ok
t/filter/in_bbs_body.t .................. ok
t/filter/in_bbs_consume.t ............... ok
t/filter/in_bbs_inject_header.t ......... response had protocol HTTP/0.9 (headers not sent?) at t/filter/in_bbs_inject_header.t line 38.
t/filter/in_bbs_inject_header.t ......... Dubious, test returned 255 (wstat 65280, 0xff00)
Failed 36/36 subtests
t/filter/in_bbs_msg.t ................... ok
t/filter/in_bbs_underrun.t .............. ok
t/filter/in_error.t ..................... ok
t/filter/in_init_basic.t ................ ok
t/filter/in_str_bin_data.t .............. ok
t/filter/in_str_consume.t ............... ok
t/filter/in_str_declined.t .............. ok
t/filter/in_str_declined_read.t ......... ok
t/filter/in_str_lc.t .................... ok
t/filter/in_str_msg.t ................... request has failed (the response code was: 404)
see t/logs/error_log for more details
t/filter/in_str_msg.t ................... Dubious, test returned 255 (wstat 65280, 0xff00)
No subtests run
t/filter/in_str_sandwich.t .............. ok
t/filter/out_apache.t ................... ok
t/filter/out_bbs_basic.t ................ ok
t/filter/out_bbs_ctx.t .................. ok
t/filter/out_bbs_filebucket.t ........... ok
t/filter/out_init_basic.t ............... ok
t/filter/out_str_api.t .................. ok
t/filter/out_str_buffer.t ............... ok
t/filter/out_str_ctx.t .................. ok
t/filter/out_str_declined.t ............. ok
t/filter/out_str_eval.t ................. ok
t/filter/out_str_lc.t ................... ok
t/filter/out_str_remove.t ............... ok
t/filter/out_str_req_eos.t .............. ok
t/filter/out_str_req_mix.t .............. ok
t/filter/out_str_reverse.t .............. ok
t/filter/out_str_subreq_default.t ....... ok
t/filter/out_str_subreq_modperl.t ....... ok
t/filter/with_subrequest.t .............. ok
t/hooks/access.t ........................ ok
t/hooks/authen_basic.t .................. ok
t/hooks/authen_digest.t ................. ok
t/hooks/authz.t ......................... ok
t/hooks/cleanup.t ....................... ok
t/hooks/cleanup2.t ...................... ok
t/hooks/error.t ......................... ok
t/hooks/fixup.t ......................... ok
t/hooks/headerparser.t .................. ok
t/hooks/hookrun.t ....................... ok
t/hooks/init.t .......................... ok
t/hooks/inlined_handlers.t .............. ok
t/hooks/push_handlers.t ................. ok
t/hooks/push_handlers_anon.t ............ ok
t/hooks/push_handlers_blessed.t ......... ok
t/hooks/push_handlers_same_phase.t ...... ok
t/hooks/set_handlers.t .................. ok
t/hooks/stacked_handlers.t .............. ok
t/hooks/stacked_handlers2.t ............. ok
t/hooks/startup.t ....................... ok
t/hooks/trans.t ......................... ok
t/modperl/cookie.t ...................... ok
t/modperl/cookie2.t ..................... ok
t/modperl/current_callback.t ............ ok
t/modperl/dir_config.t .................. ok
t/modperl/endav.t ....................... ok
t/modperl/env.t ......................... ok
t/modperl/exit.t ........................ ok
t/modperl/getc.t ........................ ok
t/modperl/interpreter.t ................. ok
t/modperl/io_nested_with_closed_stds.t .. ok
t/modperl/io_with_closed_stds.t ......... ok
t/modperl/local_env.t ................... skipped: local %ENV is still broken
t/modperl/merge.t ....................... ok
t/modperl/merge2.t ...................... ok
t/modperl/merge3.t ...................... ok
t/modperl/method.t ...................... ok
t/modperl/methodname.t .................. ok
t/modperl/methodobj.t ................... ok
t/modperl/perl.t ........................ ok
t/modperl/perl_options.t ................ ok
t/modperl/perl_options2.t ............... skipped: PerlOptions None is broken
t/modperl/pnotes.t ...................... ok
t/modperl/pnotes2.t ..................... ok
t/modperl/post_utf8.t ................... ok
t/modperl/print.t ....................... ok
t/modperl/print_utf8.t .................. ok
t/modperl/print_utf8_2.t ................ ok
t/modperl/printf.t ...................... ok
t/modperl/readline.t .................... ok
t/modperl/request_rec_perlio_api.t ...... ok
t/modperl/request_rec_tie_api.t ......... skipped: perl 5.020002: PerlIO is used instead of TIEd IO
t/modperl/setauth.t ..................... ok
t/modperl/setupenv.t .................... ok
t/modperl/setupenv2.t ................... ok
t/modperl/status.t ...................... ok
t/modperl/stdfd.t ....................... ok
t/modperl/stdfd2.t ...................... ok
t/modperl/subenv.t ...................... ok
t/modperl/taint.t ....................... ok
t/modperl/util.t ........................ ok
t/modules/apache_resource.t ............. CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at t/modules/apache_resource.t, line 11.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/apache_resource.t ............. skipped: cannot find module 'BSD::Resource'
t/modules/apache_status.t ............... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at t/modules/apache_status.t, line 16.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/apache_status.t ............... ok
t/modules/cgi.t ......................... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgi.t ......................... ok
t/modules/cgi2.t ........................ CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgi2.t ........................ ok
t/modules/cgipost.t ..................... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgipost.t ..................... ok
t/modules/cgipost2.t .................... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgipost2.t .................... ok
t/modules/cgiupload.t ................... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgiupload.t ................... ok
t/modules/cgiupload2.t .................. CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/cgiupload2.t .................. ok
t/modules/include.t ..................... CGI will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at (eval 22), line 2.
CGI::Util will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/local/lib/perl5/5.20.2/CGI.pm, line 29.
t/modules/include.t ..................... ok
t/modules/include2.t .................... ok
t/modules/include_subreq.t .............. ok
t/modules/proxy.t ....................... ok
t/perl/api.t ............................ ok
t/perl/hash_attack.t .................... skipped: relevant only for perl 5.8.2 and up to 5.17.6
t/perl/signals.t ........................ skipped: works only for prefork
t/preconnection/note.t .................. ok
t/protocol/echo_bbs.t ................... ok
t/protocol/echo_bbs2.t .................. ok
t/protocol/echo_block.t ................. 1/3

( Hang indefinitely. )


^C[warning]
halting tests
[warning] server localhost:8529 shutdown
[warning] port 8529 still in use...
done
+--------------------------------------------------------+
| Please file a bug report: http://perl.apache.org/bugs/ |
+--------------------------------------------------------+
make: *** [run_tests] Error 1

> rm t/logs/error_log
> t/TEST t/protocol/echo_block.t
[warning] setting ulimit to allow core files
ulimit -c unlimited; /usr/local/bin/perl /usr/local/src/mod_perl-2.0.9/t/TEST 't/protocol/echo_block.t'
/usr/local/httpd-2.4.17/bin/httpd -d /usr/local/src/mod_perl-2.0.9/t -f /usr/local/src/mod_perl-2.0.9/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS
using Apache/2.4.17 (worker MPM)

waiting 300 seconds for server to start: .[Thu Oct 15 13:16:29.276878 2015] [env:warn] [pid 18347:tid 140151846704896] AH01506: PassEnv variable LD_LIBRARY_PATH was undefined
[Thu Oct 15 13:16:29.305066 2015] [perl:info] [pid 18347:tid 140151846704896] 6 Apache2:: modules loaded
[Thu Oct 15 13:16:29.305154 2015] [perl:info] [pid 18347:tid 140151846704896] 0 APR:: modules loaded
[Thu Oct 15 13:16:29.305217 2015] [perl:info] [pid 18347:tid 140151846704896] base server + 29 vhosts ready to run tests
....
waiting 300 seconds for server to start: ok (waited 3 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::echo_nonblock)
server localhost:8543 listening (TestProtocol::echo_block)
server localhost:8544 listening (TestProtocol::echo_timeout)
server localhost:8545 listening (TestProtocol::echo_bbs2)
server localhost:8546 listening (TestProtocol::pseudo_http)
server localhost:8547 listening (TestProtocol::echo_filter)
server localhost:8548 listening (TestProtocol::echo_bbs)
server localhost:8549 listening (TestPreConnection::note)
server localhost:8550 listening (TestHooks::startup)
server localhost:8551 listening (TestHooks::hookrun)
server localhost:8552 listening (TestHooks::trans)
server localhost:8553 listening (TestHooks::stacked_handlers2)
server localhost:8554 listening (TestHooks::init)
server localhost:8555 listening (TestFilter::both_str_con_add)
server localhost:8556 listening (TestFilter::in_bbs_inject_header)
server localhost:8557 listening (TestFilter::in_str_msg)
server localhost:8558 listening (TestFilter::in_bbs_msg)
server localhost:8559 listening (TestDirective::perlrequire)
server localhost:8560 listening (TestDirective::perlmodule)
server localhost:8561 listening (TestDirective::perlloadmodule3)
server localhost:8562 listening (TestDirective::perlloadmodule5)
server localhost:8563 listening (TestDirective::perlloadmodule4)
server localhost:8564 listening (TestAPI::add_config)
server localhost:8565 listening (TestDirective::perlloadmodule6)
server localhost:8566 listening (TestHooks::push_handlers_anon)
t/protocol/echo_block.t .. 1/3

( Hang indefinitely. )

^C[warning]
halting tests
[warning] server localhost:8529 shutdown
[warning] port 8529 still in use...
done

> cat t/logs/error_log
[Thu Oct 15 13:16:30.001320 2015] [ssl:info] [pid 18347:tid 140151846704896] AH01887: Init: Initializing (virtual) servers for SSL
[Thu Oct 15 13:16:30.001370 2015] [ssl:info] [pid 18347:tid 140151846704896] AH01876: mod_ssl/2.4.17 compiled against Server: Apache/2.4.17, Library: OpenSSL/1.0.1e
[Thu Oct 15 13:16:30.001416 2015] [http2:debug] [pid 18347:tid 140151846704896] mod_http2.c(75): initializing post config dry run
[Thu Oct 15 13:16:30.002016 2015] [unique_id:info] [pid 18347:tid 140151846704896] AH01566: using ip addr 129.78.64.200
********************************************************************************
*** 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=18347
END in modperl_extra.pl, pid=18347
[Thu Oct 15 13:16:32.440095 2015] [auth_digest:notice] [pid 18351:tid 140151846704896] AH01757: generating secret for digest authentication ...
[Thu Oct 15 13:16:32.440153 2015] [auth_digest:debug] [pid 18351:tid 140151846704896] mod_auth_digest.c(250): AH01759: done
[Thu Oct 15 13:16:32.442023 2015] [ssl:warn] [pid 18351:tid 140151846704896] AH01873: Init: Session Cache is not configured [hint: SSLSessionCache]
[Thu Oct 15 13:16:32.442119 2015] [ssl:info] [pid 18351:tid 140151846704896] AH01887: Init: Initializing (virtual) servers for SSL
[Thu Oct 15 13:16:32.442137 2015] [ssl:info] [pid 18351:tid 140151846704896] AH01876: mod_ssl/2.4.17 compiled against Server: Apache/2.4.17, Library: OpenSSL/1.0.1e
[Thu Oct 15 13:16:32.442199 2015] [http2:info] [pid 18351:tid 140151846704896] mod_http2 (v1.0.0, nghttp2 1.0.2), initializing...
[Thu Oct 15 13:16:32.442286 2015] [http2:debug] [pid 18351:tid 140151846704896] h2_h2.c(72): h2_h2, child_init
[Thu Oct 15 13:16:32.442300 2015] [http2:debug] [pid 18351:tid 140151846704896] h2_switch.c(54): h2_switch init
[Thu Oct 15 13:16:32.442393 2015] [unique_id:info] [pid 18351:tid 140151846704896] AH01566: using ip addr 129.78.64.200
[Thu Oct 15 13:16:33.000833 2015] [slotmem_shm:debug] [pid 18351:tid 140151846704896] mod_slotmem_shm.c(496): AH02301: attach looking for /usr/local/src/mod_perl-2.0.9/t/logs/slotmem-shm-mod_heartmonitor.shm
[Thu Oct 15 13:16:33.000915 2015] [lbmethod_heartbeat:notice] [pid 18351:tid 140151846704896] AH02282: No slotmem from mod_heartmonitor
[Thu Oct 15 13:16:33.008017 2015] [cgid:error] [pid 18355:tid 140151846704896] (2)No such file or directory: AH01243: Couldn't bind unix domain socket /usr/local/src/mod_perl-2.0.9/t/logs/logs/cgisock.18351
[Thu Oct 15 13:16:33.179825 2015] [mpm_worker:notice] [pid 18351:tid 140151846704896] AH00292: Apache/2.4.17 (Unix) world domination series/2.0 OpenSSL/1.0.1e-fips mod_perl/2.0.9 Perl/v5.20.2 configured -- resuming normal operations
[Thu Oct 15 13:16:33.179898 2015] [mpm_worker:info] [pid 18351:tid 140151846704896] AH00293: Server built: Oct 15 2015 10:56:48
[Thu Oct 15 13:16:33.179940 2015] [core:notice] [pid 18351:tid 140151846704896] AH00094: Command line: '/usr/local/httpd-2.4.17/bin/httpd -d /usr/local/src/mod_perl-2.0.9/t -f /usr/local/src/mod_perl-2.0.9/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS'
[Thu Oct 15 13:16:33.180072 2015] [cgid:crit] [pid 18351:tid 140151846704896] AH01238: cgid daemon failed to initialize
[Thu Oct 15 13:16:33.182630 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182695 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182700 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182703 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182706 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182709 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182712 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182715 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182718 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182721 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182724 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182727 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182730 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182733 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182737 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182739 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182742 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182745 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182748 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182751 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182754 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182761 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182764 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182767 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182770 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182773 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182776 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182779 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182781 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182784 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182787 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182790 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182793 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182796 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182799 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182801 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182804 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182807 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182810 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182813 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182816 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182822 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182825 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182828 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182831 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182834 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182837 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182840 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182842 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182845 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182848 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182851 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182854 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182856 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182859 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182862 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182883 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182886 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182889 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182892 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182903 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182917 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182921 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182924 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182927 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182930 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_pr
oxy_content/ local already initialized
[Thu Oct 15 13:16:33.182933 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182936 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182939 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182942 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.182945 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:33.182948 2015] [proxy:debug] [pid 18356:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:33.913180 2015] [authz_core:debug] [pid 18356:tid 140151405184768] mod_authz_core.c(835): [client 127.0.0.1:43283] AH01628: authorization result: granted (no directives)
[Thu Oct 15 13:16:35.190719 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190892 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190904 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190910 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190917 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190923 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190930 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190936 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190963 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190969 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190976 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190982 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.190989 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.190995 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191002 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191007 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191014 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191020 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191060 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191074 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191081 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191087 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191094 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191100 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191106 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191112 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191119 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191125 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191139 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191145 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191152 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191158 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191164 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191170 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191176 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191182 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191189 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191194 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191201 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191207 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191213 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191219 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191225 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191231 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191238 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191244 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191250 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191256 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191269 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191278 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191284 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191290 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191296 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191303 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191309 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191315 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191345 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191351 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_pr
oxy_content/ local already initialized
[Thu Oct 15 13:16:35.191358 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191364 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191386 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191407 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191414 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191420 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191427 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191433 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191439 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191445 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191462 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191469 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:16:35.191475 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1785): AH00924: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ shared already initialized
[Thu Oct 15 13:16:35.191481 2015] [proxy:debug] [pid 18371:tid 140151846704896] proxy_util.c(1827): AH00926: worker http://localhost:8529/TestFilter__both_str_req_proxy_content/ local already initialized
[Thu Oct 15 13:18:01.710120 2015] [perl:info] [pid 18371:tid 140151846704896] Child process pid=18371 is exiting
[Thu Oct 15 13:18:01.710320 2015] [perl:info] [pid 18371:tid 140151846704896] Child process pid=18371 is exiting - server push
END in modperl_extra.pl, pid=18371
[Thu Oct 15 13:18:01.711802 2015] [perl:error] [pid 18356:tid 140151415674624] APR::Socket::recv: (9) Bad file descriptor at /usr/local/src/mod_perl-2.0.9/t/protocol/TestProtocol/echo_block.pm line 37
[Thu Oct 15 13:18:01.712420 2015] [perl:info] [pid 18356:tid 140151846704896] Child process pid=18356 is exiting
[Thu Oct 15 13:18:01.712571 2015] [perl:info] [pid 18356:tid 140151846704896] Child process pid=18356 is exiting - server push
END in modperl_extra.pl, pid=18356
END in modperl_extra.pl, pid=18371
END in modperl_extra.pl, pid=18356
[Thu Oct 15 13:18:03.054752 2015] [core:info] [pid 18351:tid 140151846704896] AH00096: removed PID file /usr/local/src/mod_perl-2.0.9/t/logs/httpd.pid (pid=18351)
[Thu Oct 15 13:18:03.054806 2015] [mpm_worker:notice] [pid 18351:tid 140151846704896] AH00295: 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=18351
END in modperl_extra.pl, pid=18351

---END: Bug Report---






Regards,

Jie