You are viewing a plain text version of this content. The canonical link for it is here.
Posted to modperl@perl.apache.org by Andrew Fuchs <fu...@vaniercollege.qc.ca> on 2015/08/30 23:20:51 UTC

mod_perl 2.0.9, 'make test' fails under httpd 2.4.16 and perl 5.20.1 (in t/filter/in_bbs_inject_header.t)

In mod_perl version 2.09, 'make test' fails at

  t/filter/in_bbs_inject_header.t

under apache 2.4.16 and perl 5.20.1. Perl, apache and mod_perl were all compiled locally under gcc 4.9.2.

The 3/36 failing sub-tests run under a persistent connection:

  keep_alive => 1 on the client side
  $c->keepalive == Apache2::Const::CONN_KEEPALIVE under Apache2::Connection

The problem arises on line 182 of

  t/filter/TestFilter/in_bbs_inject_header.pm

where the handler expects to get the Request-Line

  POST /TestFilter__in_bbs_inject_header HTTP/1.1

in a single bucket; unfortunately, under keep-alive, in all but the first iteration, the Request-Line spans two buckets--the first consisting of the lone character 'P'. This is borne out in the error log:

[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[P]
[  debug] filter read:
[OST /TestFilter__in_bbs_inject_header HTTP/1.1
]

As a consequence, the condition

  $data =~ /^POST/

on line 182 of t/filter/TestFilter/in_bbs_inject_header.pm fails (in my case, matching on /^P?OST/ provides a fix); the handler fails to queue the 'X-My-Protocol' header for injection; and validation in the corresponding sub-test in 'in_bbs_inject_header.t' fails.

The test, scheduled four times under the 'keep-alive' flag, passes without the fragmentation of the Request-Line only the first time 'round. Again, this is borne out in the error log (see below).

Now, I don't know enough about brigades, buckets and the writing of filters to be able to say whether this is a problem of handler implementation, or a problem on the level of the bucket brigade filter api. Where in the chain does packaging in terms of buckets and brigades take place? Under what circumstances can we assume that request headers won't span several buckets?

More than anything, I'm trying to understand if there's something wrong with my installation. Please advise!

Thanks in advance,

Andy

Below, please find the output to the following commands (labeled accordingly):

> uname -a
> t/REPORT
> t/TEST -trace=debug -verbose t/filter/in_bbs_inject_header.t
> cat t/logs/error_log
############ OUTPUT ############
> uname -a
Linux xxxxx.xxxxxxxxxxxx.xx.xx 4.1.6-xxxxx #1 SMP PREEMPT Sat Aug 22 12:37:14 EDT 2015 x86_64 GNU/Linux
> 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/httpd/mod_perl-2.0.9/lib/Apache2/BuildConfig.pm

*** Makefile.PL options:
  MP_APR_LIB     => aprext
  MP_APXS        => /usr/local/apache2/bin/apxs
  MP_COMPAT_1X   => 1
  MP_GENERATE_XS => 1
  MP_LIBNAME     => mod_perl
  MP_USE_DSO     => 1


*** /usr/local/apache2/bin/httpd -V
Server version: Apache/2.4.16 (Unix)
Server built:   Aug 28 2015 06:23:31
Server's Module Magic Number: 20120211:47
Server loaded:  APR 1.5.2, APR-UTIL 1.5.4
Compiled using: APR 1.5.2, 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/apache2"
 -D SUEXEC_BIN="/usr/local/apache2/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/apache2/bin/httpd
    linux-vdso.so.1 (0x00007ffe50fd0000)
    libpcre.so.1 => /usr/lib/libpcre.so.1 (0x00007f833cd02000)
    libaprutil-1.so.0 => /usr/local/apache2/lib/libaprutil-1.so.0 (0x00007f833cadd000)
    libexpat.so.1 => /usr/lib/libexpat.so.1 (0x00007f833c8b3000)
    libapr-1.so.0 => /usr/local/apache2/lib/libapr-1.so.0 (0x00007f833c681000)
    libuuid.so.1 => /usr/lib/libuuid.so.1 (0x00007f833c47c000)
    librt.so.1 => /usr/lib/librt.so.1 (0x00007f833c274000)
    libcrypt.so.1 => /usr/lib/libcrypt.so.1 (0x00007f833c03c000)
    libpthread.so.0 => /usr/lib/libpthread.so.0 (0x00007f833be20000)
    libdl.so.2 => /usr/lib/libdl.so.2 (0x00007f833bc1c000)
    libc.so.6 => /usr/lib/libc.so.6 (0x00007f833b879000)
    /lib64/ld-linux-x86-64.so.2 (0x00007f833cf71000)


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

 -L/usr/local/apache2/lib -laprutil-1     -lexpat
 -L/usr/local/apache2/lib -lapr-1 -luuid -lrt -lcrypt  -lpthread -ldl



*** /usr/bin/perl -V
Summary of my perl5 (revision 5 version 20 subversion 1) configuration:

  Platform:
    osname=linux, osvers=4.1.6-euler, archname=x86_64-linux-thread-multi
    uname='linux euler.vaniercollege.qc.ca 4.1.6-euler #1 smp preempt sat aug 22 12:37:14 edt 2015 x86_64 gnulinux '
    config_args='-des -Dusethreads -Duseshrplib -Doptimize=-march=x86-64 -mtune=generic -O2 -pipe -fstack-protector-strong --param=ssp-buffer-size=4 -Dprefix=/usr -Dvendorprefix=/usr -Dprivlib=/usr/share/perl5/core_perl -Darchlib=/usr/lib/perl5/core_perl -Dsitelib=/usr/share/perl5/site_perl -Dsitearch=/usr/lib/perl5/site_perl -Dvendorlib=/usr/share/perl5/vendor_perl -Dvendorarch=/usr/lib/perl5/vendor_perl -Dscriptdir=/usr/bin/core_perl -Dsitescript=/usr/bin/site_perl -Dvendorscript=/usr/bin/vendor_perl -Dinc_version_list=none -Dman1ext=1perl -Dman3ext=3perl -Dcccdlflags='-fPIC' -Dlddlflags=-shared -Wl,-O1,--sort-common,--as-needed,-z,relro -Dldflags=-Wl,-O1,--sort-common,--as-needed,-z,relro'
    hint=recommended, useposix=true, d_sigaction=define
    useithreads=define, usemultiplicity=define
    use64bitint=define, use64bitall=define, uselongdouble=undef
    usemymalloc=n, bincompat5005=undef
  Compiler:
    cc='cc', ccflags ='-D_REENTRANT -D_GNU_SOURCE -fwrapv -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64',
    optimize='-march=x86-64 -mtune=generic -O2 -pipe -fstack-protector-strong --param=ssp-buffer-size=4',
    cppflags='-D_REENTRANT -D_GNU_SOURCE -fwrapv -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include'
    ccversion='', gccversion='4.9.2 20141224 (prerelease)', 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='cc', ldflags ='-Wl,-O1,--sort-common,--as-needed,-z,relro -fstack-protector -L/usr/local/lib'
    libpth=/usr/local/lib /usr/lib/gcc/x86_64-unknown-linux-gnu/4.9.2/include-fixed /usr/lib /lib/../lib /usr/lib/../lib /lib /lib64 /usr/lib64
    libs=-lnsl -lgdbm -ldb -ldl -lm -lcrypt -lutil -lpthread -lc -lgdbm_compat
    perllibs=-lnsl -ldl -lm -lcrypt -lutil -lpthread -lc
    libc=libc-2.20.so, so=so, useshrplib=true, libperl=libperl.so
    gnulibc_version='2.20'
  Dynamic Linking:
    dlsrc=dl_dlopen.xs, dlext=so, d_dlsymun=undef, ccdlflags='-Wl,-E -Wl,-rpath,/usr/lib/perl5/core_perl/CORE'
    cccdlflags='-fPIC', lddlflags='-shared -Wl,-O1,--sort-common,--as-needed,-z,relro -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 Aug 25 2015 14:28:14
  %ENV:
    PERL_LWP_USE_HTTP_10="1"
  @INC:
    /usr/lib/perl5/site_perl
    /usr/share/perl5/site_perl
    /usr/lib/perl5/vendor_perl
    /usr/share/perl5/vendor_perl
    /usr/lib/perl5/core_perl
    /usr/share/perl5/core_perl
    .

*** Packages of interest status:

Apache2            : -
Apache2::Request   : -
CGI                : 3.65, 4.21
ExtUtils::MakeMaker: 6.98, 7.04
LWP                : 6.13
mod_perl           : -
mod_perl2          : -


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 30 15:04:25 2015 GMT.

-------------8<---------- End Bug Report --------------8<----------
> t/TEST -trace=debug -verbose t/filter/in_bbs_inject_header.t
[warning] setting ulimit to allow core files
ulimit -c unlimited; /usr/bin/perl /usr/local/src/httpd/mod_perl-2.0.9/t/TEST -trace=debug -verbose 't/filter/in_bbs_inject_header.t'
[  debug] configuring httpd
[  debug] Using httpd: /usr/local/apache2/bin/httpd
[  debug] isolated httpd_info VERSION = Apache/2.4.16 (Unix)
[  debug] isolated httpd_info BUILT = Aug 28 2015 06:23:31
[  debug] isolated httpd_info MODULE_MAGIC_NUMBER = 20120211:47
[  debug] isolated httpd_info SERVER_MPM = event
[  debug] isolated httpd_defines APR_HAS_SENDFILE = 1
[  debug] isolated httpd_defines APR_HAS_MMAP = 1
[  debug] isolated httpd_defines APR_HAVE_IPV6 (IPv4-mapped addresses enabled) = 1
[  debug] isolated httpd_defines APR_USE_SYSVSEM_SERIALIZE = 1
[  debug] isolated httpd_defines APR_USE_PTHREAD_SERIALIZE = 1
[  debug] isolated httpd_defines SINGLE_LISTEN_UNSERIALIZED_ACCEPT = 1
[  debug] isolated httpd_defines APR_HAS_OTHER_CHILD = 1
[  debug] isolated httpd_defines AP_HAVE_RELIABLE_PIPED_LOGS = 1
[  debug] isolated httpd_defines DYNAMIC_MODULE_LIMIT = 256
[  debug] isolated httpd_defines HTTPD_ROOT = /usr/local/apache2
[  debug] isolated httpd_defines SUEXEC_BIN = /usr/local/apache2/bin/suexec
[  debug] isolated httpd_defines DEFAULT_PIDLOG = logs/httpd.pid
[  debug] isolated httpd_defines DEFAULT_SCOREBOARD = logs/apache_runtime_status
[  debug] isolated httpd_defines DEFAULT_ERRORLOG = logs/error_log
[  debug] isolated httpd_defines AP_TYPES_CONFIG_FILE = conf/mime.types
[  debug] isolated httpd_defines SERVER_CONFIG_FILE = conf/httpd.conf
[  debug] inheriting config file: /usr/local/apache2/conf/httpd.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-mpm.conf
[  debug] conf/extra/httpd-mpm.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-userdir.conf
[  debug] conf/extra/httpd-userdir.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/proxy-html.conf
[  debug] conf/extra/proxy-html.conf successfully resolved to existing file /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-ssl.conf
[  debug] conf/extra/httpd-ssl.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_file.so
[  debug] modules/mod_authn_file.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_file.so
[  debug] Found: authn_file_module => mod_authn_file.c
[  debug] LoadModule authn_file_module mod_authn_file.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_core.so
[  debug] modules/mod_authn_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_core.so
[  debug] Found: authn_core_module => mod_authn_core.c
[  debug] LoadModule authn_core_module mod_authn_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_host.so
[  debug] modules/mod_authz_host.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_host.so
[  debug] Found: authz_host_module => mod_authz_host.c
[  debug] LoadModule authz_host_module mod_authz_host.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_groupfile.so
[  debug] modules/mod_authz_groupfile.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_groupfile.so
[  debug] Found: authz_groupfile_module => mod_authz_groupfile.c
[  debug] LoadModule authz_groupfile_module mod_authz_groupfile.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_user.so
[  debug] modules/mod_authz_user.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_user.so
[  debug] Found: authz_user_module => mod_authz_user.c
[  debug] LoadModule authz_user_module mod_authz_user.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_core.so
[  debug] modules/mod_authz_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_core.so
[  debug] Found: authz_core_module => mod_authz_core.c
[  debug] LoadModule authz_core_module mod_authz_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_access_compat.so
[  debug] modules/mod_access_compat.so successfully resolved to existing file /usr/local/apache2/modules/mod_access_compat.so
[  debug] Found: access_compat_module => mod_access_compat.c
[  debug] LoadModule access_compat_module mod_access_compat.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_auth_basic.so
[  debug] modules/mod_auth_basic.so successfully resolved to existing file /usr/local/apache2/modules/mod_auth_basic.so
[  debug] Found: auth_basic_module => mod_auth_basic.c
[  debug] LoadModule auth_basic_module mod_auth_basic.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_socache_shmcb.so
[  debug] modules/mod_socache_shmcb.so successfully resolved to existing file /usr/local/apache2/modules/mod_socache_shmcb.so
[  debug] Found: socache_shmcb_module => mod_socache_shmcb.c
[  debug] LoadModule socache_shmcb_module mod_socache_shmcb.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_reqtimeout.so
[  debug] modules/mod_reqtimeout.so successfully resolved to existing file /usr/local/apache2/modules/mod_reqtimeout.so
[  debug] Found: reqtimeout_module => mod_reqtimeout.c
[  debug] LoadModule reqtimeout_module mod_reqtimeout.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_filter.so
[  debug] modules/mod_filter.so successfully resolved to existing file /usr/local/apache2/modules/mod_filter.so
[  debug] Found: filter_module => mod_filter.c
[  debug] LoadModule filter_module mod_filter.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_mime.so
[  debug] modules/mod_mime.so successfully resolved to existing file /usr/local/apache2/modules/mod_mime.so
[  debug] Found: mime_module => mod_mime.c
[  debug] LoadModule mime_module mod_mime.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_log_config.so
[  debug] modules/mod_log_config.so successfully resolved to existing file /usr/local/apache2/modules/mod_log_config.so
[  debug] Found: log_config_module => mod_log_config.c
[  debug] LoadModule log_config_module mod_log_config.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_env.so
[  debug] modules/mod_env.so successfully resolved to existing file /usr/local/apache2/modules/mod_env.so
[  debug] Found: env_module => mod_env.c
[  debug] LoadModule env_module mod_env.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_headers.so
[  debug] modules/mod_headers.so successfully resolved to existing file /usr/local/apache2/modules/mod_headers.so
[  debug] Found: headers_module => mod_headers.c
[  debug] LoadModule headers_module mod_headers.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_setenvif.so
[  debug] modules/mod_setenvif.so successfully resolved to existing file /usr/local/apache2/modules/mod_setenvif.so
[  debug] Found: setenvif_module => mod_setenvif.c
[  debug] LoadModule setenvif_module mod_setenvif.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_version.so
[  debug] modules/mod_version.so successfully resolved to existing file /usr/local/apache2/modules/mod_version.so
[  debug] Found: version_module => mod_version.c
[  debug] LoadModule version_module mod_version.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_ssl.so
[  debug] modules/mod_ssl.so successfully resolved to existing file /usr/local/apache2/modules/mod_ssl.so
[  debug] Found: ssl_module => mod_ssl.c
[  debug] LoadModule ssl_module mod_ssl.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_unixd.so
[  debug] modules/mod_unixd.so successfully resolved to existing file /usr/local/apache2/modules/mod_unixd.so
[  debug] Found: unixd_module => mod_unixd.c
[  debug] LoadModule unixd_module mod_unixd.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_status.so
[  debug] modules/mod_status.so successfully resolved to existing file /usr/local/apache2/modules/mod_status.so
[  debug] Found: status_module => mod_status.c
[  debug] LoadModule status_module mod_status.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_autoindex.so
[  debug] modules/mod_autoindex.so successfully resolved to existing file /usr/local/apache2/modules/mod_autoindex.so
[  debug] Found: autoindex_module => mod_autoindex.c
[  debug] LoadModule autoindex_module mod_autoindex.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_cgi.so
[  debug] modules/mod_cgi.so successfully resolved to existing file /usr/local/apache2/modules/mod_cgi.so
[  debug] Found: cgi_module => mod_cgi.c
[  debug] LoadModule cgi_module mod_cgi.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_dir.so
[  debug] modules/mod_dir.so successfully resolved to existing file /usr/local/apache2/modules/mod_dir.so
[  debug] Found: dir_module => mod_dir.c
[  debug] LoadModule dir_module mod_dir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_userdir.so
[  debug] modules/mod_userdir.so successfully resolved to existing file /usr/local/apache2/modules/mod_userdir.so
[  debug] Found: userdir_module => mod_userdir.c
[  debug] LoadModule userdir_module mod_userdir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_alias.so
[  debug] modules/mod_alias.so successfully resolved to existing file /usr/local/apache2/modules/mod_alias.so
[  debug] Found: alias_module => mod_alias.c
[  debug] LoadModule alias_module mod_alias.c
[  debug] using httpd.conf inherited ServerRoot to resolve conf/mime.types
[  debug] conf/mime.types successfully resolved to existing file /usr/local/apache2/conf/mime.types
[  debug] Matched Apache revision Apache/2.4.16 2
[  debug] Matched Apache revminor Apache/2.4.16 4
[  debug] found mod_mime.so => /usr/local/apache2/modules/mod_mime.so
[  debug] generating htdocs/index.html
[  debug] Will 'Include' /usr/local/src/httpd/mod_perl-2.0.9/t/conf/extra.last.conf config file
[  debug] Will 'Include' /usr/local/src/httpd/mod_perl-2.0.9/t/conf/extra.conf config file
[  debug] generating conf/extra.last.conf from /usr/local/src/httpd/mod_perl-2.0.9/t/conf/extra.last.conf.in
[  debug] generating conf/extra.conf from /usr/local/src/httpd/mod_perl-2.0.9/t/conf/extra.conf.in
[  debug] generating conf/httpd.conf
[  debug] found mod_alias.so => /usr/local/apache2/modules/mod_alias.so
[  debug] found mod_perl.so => /usr/local/src/httpd/mod_perl-2.0.9/src/modules/perl/mod_perl.so
[  debug] generating conf/modperl_inc.pl
[  debug] generating conf/modperl_startup.pl
[  debug] configuring TestVhost::config
[  debug] configuring TestVhost::log
[  debug] configuring TestModules::cgi2
[  debug] configuring TestModules::cgipost2
[  debug] configuring TestModules::cgipost
[  debug] configuring TestModules::cgiupload2
[  debug] configuring TestModules::cgiupload
[  debug] configuring TestModules::include_subreq
[  debug] configuring TestModules::proxy
[  debug] configuring TestModules::cgi
[  debug] configuring TestUser::rewrite
[  debug] configuring TestAPR::brigade
[  debug] generating apr/brigade.t
[  debug] configuring TestAPR::bucket
[  debug] generating apr/bucket.t
[  debug] configuring TestAPR::base64
[  debug] generating apr/base64.t
[  debug] configuring TestAPR::os
[  debug] generating apr/os.t
[  debug] configuring TestAPR::ipsubnet
[  debug] generating apr/ipsubnet.t
[  debug] configuring TestAPR::status
[  debug] generating apr/status.t
[  debug] configuring TestAPR::string
[  debug] generating apr/string.t
[  debug] configuring TestAPR::socket
[  debug] generating apr/socket.t
[  debug] configuring TestAPR::finfo
[  debug] generating apr/finfo.t
[  debug] configuring TestAPR::util
[  debug] generating apr/util.t
[  debug] configuring TestAPR::threadrwlock
[  debug] generating apr/threadrwlock.t
[  debug] configuring TestAPR::pool
[  debug] generating apr/pool.t
[  debug] configuring TestAPR::uri
[  debug] generating apr/uri.t
[  debug] configuring TestAPR::table
[  debug] generating apr/table.t
[  debug] configuring TestAPR::uuid
[  debug] generating apr/uuid.t
[  debug] configuring TestAPR::perlio
[  debug] generating apr/perlio.t
[  debug] configuring TestAPR::sockaddr
[  debug] generating apr/sockaddr.t
[  debug] configuring TestAPR::threadmutex
[  debug] generating apr/threadmutex.t
[  debug] configuring TestAPR::date
[  debug] generating apr/date.t
[  debug] configuring TestAPR::flatten
[  debug] generating apr/flatten.t
[  debug] configuring TestAPR::pool_lifetime
[  debug] configuring TestDirective::env
[  debug] generating directive/env.t
[  debug] configuring TestDirective::perlcleanuphandler
[  debug] configuring TestDirective::perldo
[  debug] generating directive/perldo.t
[  debug] configuring TestDirective::pod
[  debug] generating directive/pod.t
[  debug] configuring TestDirective::setupenv
[  debug] configuring TestPerl::api
[  debug] generating perl/api.t
[  debug] configuring TestPerl::signals
[  debug] generating perl/signals.t
[  debug] configuring TestPerl::hash_attack
[  debug] configuring TestModperl::pnotes2
[  debug] configuring TestModperl::interpreter
[  debug] generating modperl/interpreter.t
[  debug] configuring TestModperl::post_utf8
[  debug] configuring TestModperl::print
[  debug] generating modperl/print.t
[  debug] configuring TestModperl::env
[  debug] generating modperl/env.t
[  debug] configuring TestModperl::exit
[  debug] configuring TestModperl::subenv
[  debug] generating modperl/subenv.t
[  debug] configuring TestModperl::stdfd2
[  debug] generating modperl/stdfd2.t
[  debug] configuring TestModperl::print_utf8_2
[  debug] configuring TestModperl::dir_config
[  debug] generating modperl/dir_config.t
[  debug] configuring TestModperl::cookie2
[  debug] configuring TestModperl::print_utf8
[  debug] configuring TestModperl::merge
[  debug] configuring TestModperl::pnotes
[  debug] configuring TestModperl::request_rec_tie_api
[  debug] generating modperl/request_rec_tie_api.t
[  debug] configuring TestModperl::current_callback
[  debug] generating modperl/current_callback.t
[  debug] configuring TestModperl::readline
[  debug] configuring TestModperl::taint
[  debug] generating modperl/taint.t
[  debug] configuring TestModperl::status
[  debug] configuring TestModperl::local_env
[  debug] configuring TestModperl::getc
[  debug] configuring TestModperl::util
[  debug] generating modperl/util.t
[  debug] configuring TestModperl::io_nested_with_closed_stds
[  debug] generating modperl/io_nested_with_closed_stds.t
[  debug] configuring TestModperl::perl_options
[  debug] configuring TestModperl::methodobj
[  debug] generating modperl/methodobj.t
[  debug] configuring TestModperl::methodname
[  debug] generating modperl/methodname.t
[  debug] configuring TestModperl::request_rec_perlio_api
[  debug] configuring TestModperl::perl_options2
[  debug] configuring TestModperl::method
[  debug] generating modperl/method.t
[  debug] configuring TestModperl::endav
[  debug] generating modperl/endav.t
[  debug] configuring TestModperl::perl
[  debug] generating modperl/perl.t
[  debug] configuring TestModperl::io_with_closed_stds
[  debug] generating modperl/io_with_closed_stds.t
[  debug] configuring TestModperl::cookie
[  debug] configuring TestModperl::setupenv
[  debug] configuring TestModperl::setauth
[  debug] generating modperl/setauth.t
[  debug] configuring TestModperl::printf
[  debug] generating modperl/printf.t
[  debug] configuring TestModperl::stdfd
[  debug] generating modperl/stdfd.t
[  debug] configuring TestApache::write
[  debug] generating apache/write.t
[  debug] configuring TestApache::discard_rbody
[  debug] generating htdocs/util/in_out.pl
[  debug] generating htdocs/util/argv.pl
[  debug] generating htdocs/util/in_err.pl
[  debug] generating htdocs/util/env.pl
[  debug] configuring TestApache::subprocess
[  debug] generating apache/subprocess.t
[  debug] configuring TestApache::post
[  debug] configuring TestApache::scanhdrs2
[  debug] configuring TestApache::read
[  debug] configuring TestApache::daemon
[  debug] generating apache/daemon.t
[  debug] configuring TestApache::util
[  debug] generating apache/util.t
[  debug] configuring TestApache::scanhdrs
[  debug] configuring TestApache::conftree
[  debug] generating apache/conftree.t
[  debug] configuring TestApache::read3
[  debug] configuring TestApache::read2
[  debug] configuring TestApache::send_cgi_header
[  debug] configuring TestApache::read4
[  debug] configuring TestApache::content_length_header
[  debug] configuring TestApache::cgihandler
[  debug] configuring TestError::api
[  debug] generating error/api.t
[  debug] configuring TestError::syntax
[  debug] configuring TestError::runtime
[  debug] configuring TestAPI::aplog
[  debug] generating api/aplog.t
[  debug] configuring TestAPI::request_subclass
[  debug] generating api/request_subclass.t
[  debug] configuring TestAPI::access2
[  debug] configuring TestAPI::response
[  debug] generating api/response.t
[  debug] configuring TestAPI::rflush
[  debug] configuring TestAPI::module
[  debug] generating api/module.t
[  debug] configuring TestAPI::server_const
[  debug] generating api/server_const.t
[  debug] configuring TestAPI::show
[  debug] generating api/show.t
[  debug] configuring TestAPI::sub_request
[  debug] generating api/sub_request.t
[  debug] configuring TestAPI::query
[  debug] generating api/query.t
[  debug] configuring TestAPI::server_rec
[  debug] generating api/server_rec.t
[  debug] configuring TestAPI::internal_redirect
[  debug] configuring TestAPI::content_encoding
[  debug] configuring TestAPI::lookup_misc
[  debug] configuring TestAPI::status
[  debug] configuring TestAPI::err_headers_out
[  debug] configuring TestAPI::conn_util
[  debug] generating api/conn_util.t
[  debug] configuring TestAPI::in_out_filters
[  debug] configuring TestAPI::internal_redirect_handler
[  debug] configuring TestAPI::access2_24
[  debug] configuring TestAPI::request_rec
[  debug] configuring TestAPI::server_util
[  debug] generating api/server_util.t
[  debug] configuring TestAPI::sendfile
[  debug] configuring TestAPI::lookup_uri
[  debug] configuring TestAPI::uri
[  debug] generating api/uri.t
[  debug] configuring TestAPI::access
[  debug] generating api/access.t
[  debug] configuring TestAPI::conn_rec
[  debug] generating api/conn_rec.t
[  debug] configuring TestAPI::slurp_filename
[  debug] configuring TestAPI::command
[  debug] generating api/command.t
[  debug] configuring TestAPI::custom_response
[  debug] configuring TestAPI::lookup_uri2
[  debug] generating api/lookup_uri2.t
[  debug] configuring TestAPI::process
[  debug] generating api/process.t
[  debug] configuring TestAPI::request_util
[  debug] generating api/request_util.t
[  debug] configuring TestCompat::apache_table
[  debug] generating compat/apache_table.t
[  debug] configuring TestCompat::apache_util
[  debug] generating compat/apache_util.t
[  debug] configuring TestCompat::conn_rec
[  debug] generating compat/conn_rec.t
[  debug] configuring TestCompat::request_body
[  debug] configuring TestCompat::conn_authen
[  debug] configuring TestCompat::apache
[  debug] generating compat/apache.t
[  debug] configuring TestCompat::apache_uri
[  debug] generating compat/apache_uri.t
[  debug] configuring TestCompat::send_fd
[  debug] configuring TestCompat::apache_file
[  debug] generating compat/apache_file.t
[  debug] configuring TestCompat::request
[  debug] generating compat/request.t
[  debug] configuring TestProtocol::echo_nonblock
[  debug] configuring TestProtocol::echo_bbs2
[  debug] configuring TestProtocol::pseudo_http
[  debug] configuring TestProtocol::echo_bbs
[  debug] configuring TestProtocol::echo_block
[  debug] configuring TestProtocol::echo_filter
[  debug] configuring TestProtocol::echo_timeout
[  debug] configuring TestPreConnection::note
[  debug] configuring TestHooks::init
[  debug] configuring TestHooks::headerparser
[  debug] generating hooks/headerparser.t
[  debug] configuring TestHooks::push_handlers_blessed
[  debug] generating hooks/push_handlers_blessed.t
[  debug] configuring TestHooks::hookrun
[  debug] configuring TestHooks::inlined_handlers
[  debug] configuring TestHooks::push_handlers
[  debug] configuring TestHooks::authen_basic
[  debug] configuring TestHooks::authen_digest
[  debug] configuring TestHooks::cleanup
[  debug] configuring TestHooks::stacked_handlers2
[  debug] configuring TestHooks::trans
[  debug] configuring TestHooks::error
[  debug] configuring TestHooks::startup
[  debug] configuring TestHooks::access
[  debug] configuring TestHooks::authz
[  debug] configuring TestHooks::push_handlers_same_phase
[  debug] generating hooks/push_handlers_same_phase.t
[  debug] configuring TestHooks::stacked_handlers
[  debug] configuring TestHooks::cleanup2
[  debug] configuring TestHooks::fixup
[  debug] generating hooks/fixup.t
[  debug] configuring TestHooks::set_handlers
[  debug] generating hooks/set_handlers.t
[  debug] configuring TestFilter::out_str_subreq_modperl
[  debug] configuring TestFilter::in_bbs_body
[  debug] configuring TestFilter::in_bbs_msg
[  debug] configuring TestFilter::in_error
[  debug] configuring TestFilter::in_str_sandwich
[  debug] configuring TestFilter::out_bbs_ctx
[  debug] configuring TestFilter::with_subrequest
[  debug] configuring TestFilter::in_str_lc
[  debug] configuring TestFilter::out_str_ctx
[  debug] configuring TestFilter::in_str_bin_data
[  debug] configuring TestFilter::out_str_req_eos
[  debug] configuring TestFilter::out_bbs_basic
[  debug] generating filter/out_bbs_basic.t
[  debug] configuring TestFilter::out_str_reverse
[  debug] configuring TestFilter::out_str_lc
[  debug] configuring TestFilter::in_str_msg
[  debug] configuring TestFilter::out_init_basic
[  debug] configuring TestFilter::in_str_declined
[  debug] configuring TestFilter::both_str_native_remove
[  debug] configuring TestFilter::both_str_con_add
[  debug] configuring TestFilter::in_bbs_underrun
[  debug] configuring TestFilter::in_init_basic
[  debug] configuring TestFilter::out_str_subreq_default
[  debug] configuring TestFilter::out_bbs_filebucket
[  debug] configuring TestFilter::both_str_req_proxy
[  debug] configuring TestFilter::out_str_req_mix
[  debug] configuring TestFilter::in_bbs_inject_header
[  debug] configuring TestFilter::out_str_eval
[  debug] generating filter/out_str_eval.t
[  debug] configuring TestFilter::in_autoload
[  debug] configuring TestFilter::out_str_declined
[  debug] configuring TestFilter::in_bbs_consume
[  debug] configuring TestFilter::in_str_consume
[  debug] configuring TestFilter::out_str_api
[  debug] generating filter/out_str_api.t
[  debug] configuring TestFilter::out_str_remove
[  debug] configuring TestFilter::out_str_buffer
[  debug] configuring TestFilter::both_str_req_add
[  debug] configuring TestFilter::in_str_declined_read
[  debug] configuring TestFilter::both_str_req_mix
[  debug] generating htdocs/testdirective/main/ApacheTest/PerlRequireTest.pm
[  debug] generating htdocs/testdirective/vh/ApacheTest/PerlRequireTest.pm
[  debug] configuring TestDirective::perlrequire
[  debug] generating htdocs/testdirective/perlmodule-vh/ApacheTest/PerlModuleTest.pm
[  debug] configuring TestDirective::perlmodule
[  debug] configuring TestDirective::perlloadmodule2
[  debug] configuring TestDirective::perlloadmodule
[  debug] generating directive/perlloadmodule.t
[  debug] configuring TestDirective::perlloadmodule3
[  debug] configuring TestDirective::perlloadmodule5
[  debug] configuring TestDirective::cmdparms
[  debug] generating directive/cmdparms.t
[  debug] configuring TestDirective::perlloadmodule7
[  debug] generating directive/perlloadmodule7.t
[  debug] configuring TestDirective::perlloadmodule4
[  debug] configuring TestModperl::setupenv2
[  debug] configuring TestAPI::add_config
[  debug] configuring TestCompat::apache_module
[  debug] generating compat/apache_module.t
[  debug] configuring TestDirective::perlloadmodule6
[  debug] configuring TestHooks::push_handlers_anon
[  debug] generating conf/apache_test_config.pm
[  debug] saving config data to apache_test_config.pm
/usr/local/apache2/bin/httpd  -d /usr/local/src/httpd/mod_perl-2.0.9/t -f /usr/local/src/httpd/mod_perl-2.0.9/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS
using Apache/2.4.16 (event MPM)

waiting 300 seconds for server to start: .[Sun Aug 30 11:45:37.889448 2015] [env:warn] [pid 29223:tid 140013969753984] AH01506: PassEnv variable LD_LIBRARY_PATH was undefined
[Sun Aug 30 11:45:37.921005 2015] [perl:info] [pid 29223:tid 140013969753984] 6 Apache2:: modules loaded
[Sun Aug 30 11:45:37.921079 2015] [perl:info] [pid 29223:tid 140013969753984] 0 APR:: modules loaded
[Sun Aug 30 11:45:37.921145 2015] [perl:info] [pid 29223:tid 140013969753984] base server + 29 vhosts ready to run tests
[  debug] configuring httpd
[  debug] Using httpd: /usr/local/apache2/bin/httpd
[  debug] isolated httpd_info VERSION = Apache/2.4.16 (Unix)
[  debug] isolated httpd_info BUILT = Aug 28 2015 06:23:31
[  debug] isolated httpd_info MODULE_MAGIC_NUMBER = 20120211:47
[  debug] isolated httpd_info SERVER_MPM = event
[  debug] isolated httpd_defines APR_HAS_SENDFILE = 1
[  debug] isolated httpd_defines APR_HAS_MMAP = 1
[  debug] isolated httpd_defines APR_HAVE_IPV6 (IPv4-mapped addresses enabled) = 1
[  debug] isolated httpd_defines APR_USE_SYSVSEM_SERIALIZE = 1
[  debug] isolated httpd_defines APR_USE_PTHREAD_SERIALIZE = 1
[  debug] isolated httpd_defines SINGLE_LISTEN_UNSERIALIZED_ACCEPT = 1
[  debug] isolated httpd_defines APR_HAS_OTHER_CHILD = 1
[  debug] isolated httpd_defines AP_HAVE_RELIABLE_PIPED_LOGS = 1
[  debug] isolated httpd_defines DYNAMIC_MODULE_LIMIT = 256
[  debug] isolated httpd_defines HTTPD_ROOT = /usr/local/apache2
[  debug] isolated httpd_defines SUEXEC_BIN = /usr/local/apache2/bin/suexec
[  debug] isolated httpd_defines DEFAULT_PIDLOG = logs/httpd.pid
[  debug] isolated httpd_defines DEFAULT_SCOREBOARD = logs/apache_runtime_status
[  debug] isolated httpd_defines DEFAULT_ERRORLOG = logs/error_log
[  debug] isolated httpd_defines AP_TYPES_CONFIG_FILE = conf/mime.types
[  debug] isolated httpd_defines SERVER_CONFIG_FILE = conf/httpd.conf
[  debug] inheriting config file: /usr/local/apache2/conf/httpd.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-mpm.conf
[  debug] conf/extra/httpd-mpm.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-userdir.conf
[  debug] conf/extra/httpd-userdir.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/proxy-html.conf
[  debug] conf/extra/proxy-html.conf successfully resolved to existing file /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-ssl.conf
[  debug] conf/extra/httpd-ssl.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/mime.types
[  debug] conf/mime.types successfully resolved to existing file /usr/local/apache2/conf/mime.types
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_file.so
[  debug] modules/mod_authn_file.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_file.so
[  debug] Found: authn_file_module => mod_authn_file.c
[  debug] LoadModule authn_file_module mod_authn_file.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_core.so
[  debug] modules/mod_authn_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_core.so
[  debug] Found: authn_core_module => mod_authn_core.c
[  debug] LoadModule authn_core_module mod_authn_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_host.so
[  debug] modules/mod_authz_host.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_host.so
[  debug] Found: authz_host_module => mod_authz_host.c
[  debug] LoadModule authz_host_module mod_authz_host.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_groupfile.so
[  debug] modules/mod_authz_groupfile.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_groupfile.so
[  debug] Found: authz_groupfile_module => mod_authz_groupfile.c
[  debug] LoadModule authz_groupfile_module mod_authz_groupfile.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_user.so
[  debug] modules/mod_authz_user.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_user.so
[  debug] Found: authz_user_module => mod_authz_user.c
[  debug] LoadModule authz_user_module mod_authz_user.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_core.so
[  debug] modules/mod_authz_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_core.so
[  debug] Found: authz_core_module => mod_authz_core.c
[  debug] LoadModule authz_core_module mod_authz_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_access_compat.so
[  debug] modules/mod_access_compat.so successfully resolved to existing file /usr/local/apache2/modules/mod_access_compat.so
[  debug] Found: access_compat_module => mod_access_compat.c
[  debug] LoadModule access_compat_module mod_access_compat.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_auth_basic.so
[  debug] modules/mod_auth_basic.so successfully resolved to existing file /usr/local/apache2/modules/mod_auth_basic.so
[  debug] Found: auth_basic_module => mod_auth_basic.c
[  debug] LoadModule auth_basic_module mod_auth_basic.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_socache_shmcb.so
[  debug] modules/mod_socache_shmcb.so successfully resolved to existing file /usr/local/apache2/modules/mod_socache_shmcb.so
[  debug] Found: socache_shmcb_module => mod_socache_shmcb.c
[  debug] LoadModule socache_shmcb_module mod_socache_shmcb.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_reqtimeout.so
[  debug] modules/mod_reqtimeout.so successfully resolved to existing file /usr/local/apache2/modules/mod_reqtimeout.so
[  debug] Found: reqtimeout_module => mod_reqtimeout.c
[  debug] LoadModule reqtimeout_module mod_reqtimeout.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_filter.so
[  debug] modules/mod_filter.so successfully resolved to existing file /usr/local/apache2/modules/mod_filter.so
[  debug] Found: filter_module => mod_filter.c
[  debug] LoadModule filter_module mod_filter.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_mime.so
[  debug] modules/mod_mime.so successfully resolved to existing file /usr/local/apache2/modules/mod_mime.so
[  debug] Found: mime_module => mod_mime.c
[  debug] LoadModule mime_module mod_mime.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_log_config.so
[  debug] modules/mod_log_config.so successfully resolved to existing file /usr/local/apache2/modules/mod_log_config.so
[  debug] Found: log_config_module => mod_log_config.c
[  debug] LoadModule log_config_module mod_log_config.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_env.so
[  debug] modules/mod_env.so successfully resolved to existing file /usr/local/apache2/modules/mod_env.so
[  debug] Found: env_module => mod_env.c
[  debug] LoadModule env_module mod_env.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_headers.so
[  debug] modules/mod_headers.so successfully resolved to existing file /usr/local/apache2/modules/mod_headers.so
[  debug] Found: headers_module => mod_headers.c
[  debug] LoadModule headers_module mod_headers.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_setenvif.so
[  debug] modules/mod_setenvif.so successfully resolved to existing file /usr/local/apache2/modules/mod_setenvif.so
[  debug] Found: setenvif_module => mod_setenvif.c
[  debug] LoadModule setenvif_module mod_setenvif.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_version.so
[  debug] modules/mod_version.so successfully resolved to existing file /usr/local/apache2/modules/mod_version.so
[  debug] Found: version_module => mod_version.c
[  debug] LoadModule version_module mod_version.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_ssl.so
[  debug] modules/mod_ssl.so successfully resolved to existing file /usr/local/apache2/modules/mod_ssl.so
[  debug] Found: ssl_module => mod_ssl.c
[  debug] LoadModule ssl_module mod_ssl.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_unixd.so
[  debug] modules/mod_unixd.so successfully resolved to existing file /usr/local/apache2/modules/mod_unixd.so
[  debug] Found: unixd_module => mod_unixd.c
[  debug] LoadModule unixd_module mod_unixd.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_status.so
[  debug] modules/mod_status.so successfully resolved to existing file /usr/local/apache2/modules/mod_status.so
[  debug] Found: status_module => mod_status.c
[  debug] LoadModule status_module mod_status.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_autoindex.so
[  debug] modules/mod_autoindex.so successfully resolved to existing file /usr/local/apache2/modules/mod_autoindex.so
[  debug] Found: autoindex_module => mod_autoindex.c
[  debug] LoadModule autoindex_module mod_autoindex.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_cgi.so
[  debug] modules/mod_cgi.so successfully resolved to existing file /usr/local/apache2/modules/mod_cgi.so
[  debug] Found: cgi_module => mod_cgi.c
[  debug] LoadModule cgi_module mod_cgi.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_dir.so
[  debug] modules/mod_dir.so successfully resolved to existing file /usr/local/apache2/modules/mod_dir.so
[  debug] Found: dir_module => mod_dir.c
[  debug] LoadModule dir_module mod_dir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_userdir.so
[  debug] modules/mod_userdir.so successfully resolved to existing file /usr/local/apache2/modules/mod_userdir.so
[  debug] Found: userdir_module => mod_userdir.c
[  debug] LoadModule userdir_module mod_userdir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_alias.so
[  debug] modules/mod_alias.so successfully resolved to existing file /usr/local/apache2/modules/mod_alias.so
[  debug] Found: alias_module => mod_alias.c
[  debug] LoadModule alias_module mod_alias.c
[  debug] Matched Apache revision Apache/2.4.16 2
[  debug] Matched Apache revminor Apache/2.4.16 4
[  debug] TestDirective::perlloadmodule->SERVER_CREATE

..
waiting 300 seconds for server to start: ok (waited 2 secs)
server localhost.localdomain:8529 started
server localhost.localdomain:8530 listening (perlsections)
server localhost.localdomain:8531 listening (inherit)
server localhost.localdomain:8532 listening (filter_out_apache)
server localhost.localdomain:8533 listening (TestVhost::config)
server localhost.localdomain:8534 listening (TestVhost::log)
server localhost.localdomain:8535 listening (TestModules::proxy)
server localhost.localdomain:8536 listening (TestUser::rewrite)
server localhost.localdomain:8537 listening (TestDirective::perlcleanuphandler)
server localhost.localdomain:8538 listening (TestModperl::merge)
server localhost.localdomain:8539 listening (TestModperl::perl_options)
server localhost.localdomain:8540 listening (TestModperl::perl_options2)
server localhost.localdomain:8541 listening (TestModperl::setupenv)
server localhost.localdomain:8542 listening (TestProtocol::echo_nonblock)
server localhost.localdomain:8543 listening (TestProtocol::echo_bbs2)
server localhost.localdomain:8544 listening (TestProtocol::pseudo_http)
server localhost.localdomain:8545 listening (TestProtocol::echo_bbs)
server localhost.localdomain:8546 listening (TestProtocol::echo_block)
server localhost.localdomain:8547 listening (TestProtocol::echo_filter)
server localhost.localdomain:8548 listening (TestProtocol::echo_timeout)
server localhost.localdomain:8549 listening (TestPreConnection::note)
server localhost.localdomain:8550 listening (TestHooks::init)
server localhost.localdomain:8551 listening (TestHooks::hookrun)
server localhost.localdomain:8552 listening (TestHooks::stacked_handlers2)
server localhost.localdomain:8553 listening (TestHooks::trans)
server localhost.localdomain:8554 listening (TestHooks::startup)
server localhost.localdomain:8555 listening (TestFilter::in_bbs_msg)
server localhost.localdomain:8556 listening (TestFilter::in_str_msg)
server localhost.localdomain:8557 listening (TestFilter::both_str_con_add)
server localhost.localdomain:8558 listening (TestFilter::in_bbs_inject_header)
server localhost.localdomain:8559 listening (TestDirective::perlrequire)
server localhost.localdomain:8560 listening (TestDirective::perlmodule)
server localhost.localdomain:8561 listening (TestDirective::perlloadmodule3)
server localhost.localdomain:8562 listening (TestDirective::perlloadmodule5)
server localhost.localdomain:8563 listening (TestDirective::perlloadmodule4)
server localhost.localdomain:8564 listening (TestAPI::add_config)
server localhost.localdomain:8565 listening (TestDirective::perlloadmodule6)
server localhost.localdomain:8566 listening (TestHooks::push_handlers_anon)
[  debug] configuring httpd
[  debug] Using httpd: /usr/local/apache2/bin/httpd
[  debug] isolated httpd_info VERSION = Apache/2.4.16 (Unix)
[  debug] isolated httpd_info BUILT = Aug 28 2015 06:23:31
[  debug] isolated httpd_info MODULE_MAGIC_NUMBER = 20120211:47
[  debug] isolated httpd_info SERVER_MPM = event
[  debug] isolated httpd_defines APR_HAS_SENDFILE = 1
[  debug] isolated httpd_defines APR_HAS_MMAP = 1
[  debug] isolated httpd_defines APR_HAVE_IPV6 (IPv4-mapped addresses enabled) = 1
[  debug] isolated httpd_defines APR_USE_SYSVSEM_SERIALIZE = 1
[  debug] isolated httpd_defines APR_USE_PTHREAD_SERIALIZE = 1
[  debug] isolated httpd_defines SINGLE_LISTEN_UNSERIALIZED_ACCEPT = 1
[  debug] isolated httpd_defines APR_HAS_OTHER_CHILD = 1
[  debug] isolated httpd_defines AP_HAVE_RELIABLE_PIPED_LOGS = 1
[  debug] isolated httpd_defines DYNAMIC_MODULE_LIMIT = 256
[  debug] isolated httpd_defines HTTPD_ROOT = /usr/local/apache2
[  debug] isolated httpd_defines SUEXEC_BIN = /usr/local/apache2/bin/suexec
[  debug] isolated httpd_defines DEFAULT_PIDLOG = logs/httpd.pid
[  debug] isolated httpd_defines DEFAULT_SCOREBOARD = logs/apache_runtime_status
[  debug] isolated httpd_defines DEFAULT_ERRORLOG = logs/error_log
[  debug] isolated httpd_defines AP_TYPES_CONFIG_FILE = conf/mime.types
[  debug] isolated httpd_defines SERVER_CONFIG_FILE = conf/httpd.conf
[  debug] inheriting config file: /usr/local/apache2/conf/httpd.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-mpm.conf
[  debug] conf/extra/httpd-mpm.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-userdir.conf
[  debug] conf/extra/httpd-userdir.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/proxy-html.conf
[  debug] conf/extra/proxy-html.conf successfully resolved to existing file /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-ssl.conf
[  debug] conf/extra/httpd-ssl.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_file.so
[  debug] modules/mod_authn_file.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_file.so
[  debug] Found: authn_file_module => mod_authn_file.c
[  debug] LoadModule authn_file_module mod_authn_file.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_core.so
[  debug] modules/mod_authn_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_core.so
[  debug] Found: authn_core_module => mod_authn_core.c
[  debug] LoadModule authn_core_module mod_authn_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_host.so
[  debug] modules/mod_authz_host.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_host.so
[  debug] Found: authz_host_module => mod_authz_host.c
[  debug] LoadModule authz_host_module mod_authz_host.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_groupfile.so
[  debug] modules/mod_authz_groupfile.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_groupfile.so
[  debug] Found: authz_groupfile_module => mod_authz_groupfile.c
[  debug] LoadModule authz_groupfile_module mod_authz_groupfile.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_user.so
[  debug] modules/mod_authz_user.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_user.so
[  debug] Found: authz_user_module => mod_authz_user.c
[  debug] LoadModule authz_user_module mod_authz_user.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_core.so
[  debug] modules/mod_authz_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_core.so
[  debug] Found: authz_core_module => mod_authz_core.c
[  debug] LoadModule authz_core_module mod_authz_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_access_compat.so
[  debug] modules/mod_access_compat.so successfully resolved to existing file /usr/local/apache2/modules/mod_access_compat.so
[  debug] Found: access_compat_module => mod_access_compat.c
[  debug] LoadModule access_compat_module mod_access_compat.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_auth_basic.so
[  debug] modules/mod_auth_basic.so successfully resolved to existing file /usr/local/apache2/modules/mod_auth_basic.so
[  debug] Found: auth_basic_module => mod_auth_basic.c
[  debug] LoadModule auth_basic_module mod_auth_basic.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_socache_shmcb.so
[  debug] modules/mod_socache_shmcb.so successfully resolved to existing file /usr/local/apache2/modules/mod_socache_shmcb.so
[  debug] Found: socache_shmcb_module => mod_socache_shmcb.c
[  debug] LoadModule socache_shmcb_module mod_socache_shmcb.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_reqtimeout.so
[  debug] modules/mod_reqtimeout.so successfully resolved to existing file /usr/local/apache2/modules/mod_reqtimeout.so
[  debug] Found: reqtimeout_module => mod_reqtimeout.c
[  debug] LoadModule reqtimeout_module mod_reqtimeout.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_filter.so
[  debug] modules/mod_filter.so successfully resolved to existing file /usr/local/apache2/modules/mod_filter.so
[  debug] Found: filter_module => mod_filter.c
[  debug] LoadModule filter_module mod_filter.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_mime.so
[  debug] modules/mod_mime.so successfully resolved to existing file /usr/local/apache2/modules/mod_mime.so
[  debug] Found: mime_module => mod_mime.c
[  debug] LoadModule mime_module mod_mime.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_log_config.so
[  debug] modules/mod_log_config.so successfully resolved to existing file /usr/local/apache2/modules/mod_log_config.so
[  debug] Found: log_config_module => mod_log_config.c
[  debug] LoadModule log_config_module mod_log_config.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_env.so
[  debug] modules/mod_env.so successfully resolved to existing file /usr/local/apache2/modules/mod_env.so
[  debug] Found: env_module => mod_env.c
[  debug] LoadModule env_module mod_env.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_headers.so
[  debug] modules/mod_headers.so successfully resolved to existing file /usr/local/apache2/modules/mod_headers.so
[  debug] Found: headers_module => mod_headers.c
[  debug] LoadModule headers_module mod_headers.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_setenvif.so
[  debug] modules/mod_setenvif.so successfully resolved to existing file /usr/local/apache2/modules/mod_setenvif.so
[  debug] Found: setenvif_module => mod_setenvif.c
[  debug] LoadModule setenvif_module mod_setenvif.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_version.so
[  debug] modules/mod_version.so successfully resolved to existing file /usr/local/apache2/modules/mod_version.so
[  debug] Found: version_module => mod_version.c
[  debug] LoadModule version_module mod_version.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_ssl.so
[  debug] modules/mod_ssl.so successfully resolved to existing file /usr/local/apache2/modules/mod_ssl.so
[  debug] Found: ssl_module => mod_ssl.c
[  debug] LoadModule ssl_module mod_ssl.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_unixd.so
[  debug] modules/mod_unixd.so successfully resolved to existing file /usr/local/apache2/modules/mod_unixd.so
[  debug] Found: unixd_module => mod_unixd.c
[  debug] LoadModule unixd_module mod_unixd.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_status.so
[  debug] modules/mod_status.so successfully resolved to existing file /usr/local/apache2/modules/mod_status.so
[  debug] Found: status_module => mod_status.c
[  debug] LoadModule status_module mod_status.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_autoindex.so
[  debug] modules/mod_autoindex.so successfully resolved to existing file /usr/local/apache2/modules/mod_autoindex.so
[  debug] Found: autoindex_module => mod_autoindex.c
[  debug] LoadModule autoindex_module mod_autoindex.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_cgi.so
[  debug] modules/mod_cgi.so successfully resolved to existing file /usr/local/apache2/modules/mod_cgi.so
[  debug] Found: cgi_module => mod_cgi.c
[  debug] LoadModule cgi_module mod_cgi.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_dir.so
[  debug] modules/mod_dir.so successfully resolved to existing file /usr/local/apache2/modules/mod_dir.so
[  debug] Found: dir_module => mod_dir.c
[  debug] LoadModule dir_module mod_dir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_userdir.so
[  debug] modules/mod_userdir.so successfully resolved to existing file /usr/local/apache2/modules/mod_userdir.so
[  debug] Found: userdir_module => mod_userdir.c
[  debug] LoadModule userdir_module mod_userdir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_alias.so
[  debug] modules/mod_alias.so successfully resolved to existing file /usr/local/apache2/modules/mod_alias.so
[  debug] Found: alias_module => mod_alias.c
[  debug] LoadModule alias_module mod_alias.c
[  debug] using httpd.conf inherited ServerRoot to resolve conf/mime.types
[  debug] conf/mime.types successfully resolved to existing file /usr/local/apache2/conf/mime.types
[  debug] Matched Apache revision Apache/2.4.16 2
[  debug] Matched Apache revminor Apache/2.4.16 4
[  debug] configuring httpd
[  debug] Using httpd: /usr/local/apache2/bin/httpd
[  debug] isolated httpd_info VERSION = Apache/2.4.16 (Unix)
[  debug] isolated httpd_info BUILT = Aug 28 2015 06:23:31
[  debug] isolated httpd_info MODULE_MAGIC_NUMBER = 20120211:47
[  debug] isolated httpd_info SERVER_MPM = event
[  debug] isolated httpd_defines APR_HAS_SENDFILE = 1
[  debug] isolated httpd_defines APR_HAS_MMAP = 1
[  debug] isolated httpd_defines APR_HAVE_IPV6 (IPv4-mapped addresses enabled) = 1
[  debug] isolated httpd_defines APR_USE_SYSVSEM_SERIALIZE = 1
[  debug] isolated httpd_defines APR_USE_PTHREAD_SERIALIZE = 1
[  debug] isolated httpd_defines SINGLE_LISTEN_UNSERIALIZED_ACCEPT = 1
[  debug] isolated httpd_defines APR_HAS_OTHER_CHILD = 1
[  debug] isolated httpd_defines AP_HAVE_RELIABLE_PIPED_LOGS = 1
[  debug] isolated httpd_defines DYNAMIC_MODULE_LIMIT = 256
[  debug] isolated httpd_defines HTTPD_ROOT = /usr/local/apache2
[  debug] isolated httpd_defines SUEXEC_BIN = /usr/local/apache2/bin/suexec
[  debug] isolated httpd_defines DEFAULT_PIDLOG = logs/httpd.pid
[  debug] isolated httpd_defines DEFAULT_SCOREBOARD = logs/apache_runtime_status
[  debug] isolated httpd_defines DEFAULT_ERRORLOG = logs/error_log
[  debug] isolated httpd_defines AP_TYPES_CONFIG_FILE = conf/mime.types
[  debug] isolated httpd_defines SERVER_CONFIG_FILE = conf/httpd.conf
[  debug] inheriting config file: /usr/local/apache2/conf/httpd.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-mpm.conf
[  debug] conf/extra/httpd-mpm.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-mpm.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-userdir.conf
[  debug] conf/extra/httpd-userdir.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-userdir.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/proxy-html.conf
[  debug] conf/extra/proxy-html.conf successfully resolved to existing file /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/proxy-html.conf
[  debug] using httpd.conf inherited ServerRoot to resolve conf/extra/httpd-ssl.conf
[  debug] conf/extra/httpd-ssl.conf successfully resolved to existing file /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] inheriting config file: /usr/local/apache2/conf/extra/httpd-ssl.conf
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_file.so
[  debug] modules/mod_authn_file.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_file.so
[  debug] Found: authn_file_module => mod_authn_file.c
[  debug] LoadModule authn_file_module mod_authn_file.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authn_core.so
[  debug] modules/mod_authn_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authn_core.so
[  debug] Found: authn_core_module => mod_authn_core.c
[  debug] LoadModule authn_core_module mod_authn_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_host.so
[  debug] modules/mod_authz_host.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_host.so
[  debug] Found: authz_host_module => mod_authz_host.c
[  debug] LoadModule authz_host_module mod_authz_host.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_groupfile.so
[  debug] modules/mod_authz_groupfile.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_groupfile.so
[  debug] Found: authz_groupfile_module => mod_authz_groupfile.c
[  debug] LoadModule authz_groupfile_module mod_authz_groupfile.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_user.so
[  debug] modules/mod_authz_user.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_user.so
[  debug] Found: authz_user_module => mod_authz_user.c
[  debug] LoadModule authz_user_module mod_authz_user.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_authz_core.so
[  debug] modules/mod_authz_core.so successfully resolved to existing file /usr/local/apache2/modules/mod_authz_core.so
[  debug] Found: authz_core_module => mod_authz_core.c
[  debug] LoadModule authz_core_module mod_authz_core.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_access_compat.so
[  debug] modules/mod_access_compat.so successfully resolved to existing file /usr/local/apache2/modules/mod_access_compat.so
[  debug] Found: access_compat_module => mod_access_compat.c
[  debug] LoadModule access_compat_module mod_access_compat.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_auth_basic.so
[  debug] modules/mod_auth_basic.so successfully resolved to existing file /usr/local/apache2/modules/mod_auth_basic.so
[  debug] Found: auth_basic_module => mod_auth_basic.c
[  debug] LoadModule auth_basic_module mod_auth_basic.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_socache_shmcb.so
[  debug] modules/mod_socache_shmcb.so successfully resolved to existing file /usr/local/apache2/modules/mod_socache_shmcb.so
[  debug] Found: socache_shmcb_module => mod_socache_shmcb.c
[  debug] LoadModule socache_shmcb_module mod_socache_shmcb.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_reqtimeout.so
[  debug] modules/mod_reqtimeout.so successfully resolved to existing file /usr/local/apache2/modules/mod_reqtimeout.so
[  debug] Found: reqtimeout_module => mod_reqtimeout.c
[  debug] LoadModule reqtimeout_module mod_reqtimeout.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_filter.so
[  debug] modules/mod_filter.so successfully resolved to existing file /usr/local/apache2/modules/mod_filter.so
[  debug] Found: filter_module => mod_filter.c
[  debug] LoadModule filter_module mod_filter.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_mime.so
[  debug] modules/mod_mime.so successfully resolved to existing file /usr/local/apache2/modules/mod_mime.so
[  debug] Found: mime_module => mod_mime.c
[  debug] LoadModule mime_module mod_mime.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_log_config.so
[  debug] modules/mod_log_config.so successfully resolved to existing file /usr/local/apache2/modules/mod_log_config.so
[  debug] Found: log_config_module => mod_log_config.c
[  debug] LoadModule log_config_module mod_log_config.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_env.so
[  debug] modules/mod_env.so successfully resolved to existing file /usr/local/apache2/modules/mod_env.so
[  debug] Found: env_module => mod_env.c
[  debug] LoadModule env_module mod_env.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_headers.so
[  debug] modules/mod_headers.so successfully resolved to existing file /usr/local/apache2/modules/mod_headers.so
[  debug] Found: headers_module => mod_headers.c
[  debug] LoadModule headers_module mod_headers.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_setenvif.so
[  debug] modules/mod_setenvif.so successfully resolved to existing file /usr/local/apache2/modules/mod_setenvif.so
[  debug] Found: setenvif_module => mod_setenvif.c
[  debug] LoadModule setenvif_module mod_setenvif.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_version.so
[  debug] modules/mod_version.so successfully resolved to existing file /usr/local/apache2/modules/mod_version.so
[  debug] Found: version_module => mod_version.c
[  debug] LoadModule version_module mod_version.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_ssl.so
[  debug] modules/mod_ssl.so successfully resolved to existing file /usr/local/apache2/modules/mod_ssl.so
[  debug] Found: ssl_module => mod_ssl.c
[  debug] LoadModule ssl_module mod_ssl.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_unixd.so
[  debug] modules/mod_unixd.so successfully resolved to existing file /usr/local/apache2/modules/mod_unixd.so
[  debug] Found: unixd_module => mod_unixd.c
[  debug] LoadModule unixd_module mod_unixd.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_status.so
[  debug] modules/mod_status.so successfully resolved to existing file /usr/local/apache2/modules/mod_status.so
[  debug] Found: status_module => mod_status.c
[  debug] LoadModule status_module mod_status.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_autoindex.so
[  debug] modules/mod_autoindex.so successfully resolved to existing file /usr/local/apache2/modules/mod_autoindex.so
[  debug] Found: autoindex_module => mod_autoindex.c
[  debug] LoadModule autoindex_module mod_autoindex.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_cgi.so
[  debug] modules/mod_cgi.so successfully resolved to existing file /usr/local/apache2/modules/mod_cgi.so
[  debug] Found: cgi_module => mod_cgi.c
[  debug] LoadModule cgi_module mod_cgi.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_dir.so
[  debug] modules/mod_dir.so successfully resolved to existing file /usr/local/apache2/modules/mod_dir.so
[  debug] Found: dir_module => mod_dir.c
[  debug] LoadModule dir_module mod_dir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_userdir.so
[  debug] modules/mod_userdir.so successfully resolved to existing file /usr/local/apache2/modules/mod_userdir.so
[  debug] Found: userdir_module => mod_userdir.c
[  debug] LoadModule userdir_module mod_userdir.c
[  debug] using httpd.conf inherited ServerRoot to resolve modules/mod_alias.so
[  debug] modules/mod_alias.so successfully resolved to existing file /usr/local/apache2/modules/mod_alias.so
[  debug] Found: alias_module => mod_alias.c
[  debug] LoadModule alias_module mod_alias.c
[  debug] using httpd.conf inherited ServerRoot to resolve conf/mime.types
[  debug] conf/mime.types successfully resolved to existing file /usr/local/apache2/conf/mime.types
[  debug] Matched Apache revision Apache/2.4.16 2
[  debug] Matched Apache revminor Apache/2.4.16 4
# Failed test 22 in t/filter/in_bbs_inject_header.t at line 58 fail #6
# Failed test 26 in t/filter/in_bbs_inject_header.t at line 58 fail #7
# Failed test 30 in t/filter/in_bbs_inject_header.t at line 58 fail #8
t/filter/in_bbs_inject_header.t ..
# connecting to localhost.localdomain:8558
1..36
# Running under perl version 5.020001 for linux
# Current time local: Sun Aug 30 11:45:40 2015
# Current time GMT:   Sun Aug 30 15:45:40 2015
# Using Test.pm version 1.26
# Using Apache/Test.pm version 1.39
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 1
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 2
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 3
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 4
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 5
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 6
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 7
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 8
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 9
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 10
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 11
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 12
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 13
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 14
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 15
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 16
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 17
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 18
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 19
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 20
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 21
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: ''
not ok 22
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 23
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 24
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 25
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: ''
not ok 26
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 27
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 28
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 29
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: ''
not ok 30
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 31
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 32
# testing : body
# expected: 'This body shouldn\'t be seen by the filter'
# received: 'This body shouldn\'t be seen by the filter'
ok 33
# testing : injected header X-My-Protocol
# expected: 'POST-IT'
# received: 'POST-IT'
ok 34
# testing : injected header X-Extra-Header2
# expected: 'Value 2'
# received: 'Value 2'
ok 35
# testing : injected header X-Extra-Header3
# expected: 'Value 3'
# received: 'Value 3'
ok 36
Failed 3/36 subtests

Test Summary Report
-------------------
t/filter/in_bbs_inject_header.t (Wstat: 0 Tests: 36 Failed: 3)
  Failed tests:  22, 26, 30
Files=1, Tests=36,  1 wallclock secs ( 0.04 usr  0.01 sys +  0.62 cusr  0.14 csys =  0.81 CPU)
Result: FAIL
Failed 1/1 test programs. 3/36 subtests failed.
[warning] server localhost.localdomain:8529 shutdown
[warning] port 8529 still in use...
done
[  error] error running tests (please examine t/logs/error_log)
> cat t/logs/error_log
[  debug] Phase open_logs is completed for server at port 8529
[  debug] Phase open_logs is completed for server at port 8554
[  debug] Phase post_config is completed for server at port 8529
[  debug] Phase post_config is completed for server at port 8554
[Sun Aug 30 11:45:38.670348 2015] [ssl:info] [pid 29223:tid 140013969753984] AH01887: Init: Initializing (virtual) servers for SSL
[Sun Aug 30 11:45:38.670377 2015] [ssl:info] [pid 29223:tid 140013969753984] AH01876: mod_ssl/2.4.16 compiled against Server: Apache/2.4.16, Library: OpenSSL/1.0.2
********************************************************************************
*** 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=29223
END in modperl_extra.pl, pid=29223
[  debug] Phase open_logs is completed for server at port 8529
[  debug] Phase open_logs is completed for server at port 8554
[  debug] Phase post_config is completed for server at port 8529
[  debug] Phase post_config is completed for server at port 8554
[Sun Aug 30 11:45:39.958004 2015] [ssl:warn] [pid 29227:tid 140013969753984] AH01873: Init: Session Cache is not configured [hint: SSLSessionCache]
[Sun Aug 30 11:45:39.958019 2015] [ssl:info] [pid 29227:tid 140013969753984] AH01887: Init: Initializing (virtual) servers for SSL
[Sun Aug 30 11:45:39.958030 2015] [ssl:info] [pid 29227:tid 140013969753984] AH01876: mod_ssl/2.4.16 compiled against Server: Apache/2.4.16, Library: OpenSSL/1.0.2
[Sun Aug 30 11:45:40.099339 2015] [mpm_event:notice] [pid 29227:tid 140013969753984] AH00489: Apache/2.4.16 (Unix) world domination series/2.0 OpenSSL/1.0.2 mod_perl/2.0.9 Perl/v5.20.1 configured -- resuming normal operations
[Sun Aug 30 11:45:40.099428 2015] [mpm_event:info] [pid 29227:tid 140013969753984] AH00490: Server built: Aug 28 2015 06:23:31
[Sun Aug 30 11:45:40.099476 2015] [core:notice] [pid 29227:tid 140013969753984] AH00094: Command line: '/usr/local/apache2/bin/httpd -d /usr/local/src/httpd/mod_perl-2.0.9/t -f /usr/local/src/httpd/mod_perl-2.0.9/t/conf/httpd.conf -D APACHE2 -D APACHE2_4 -D PERL_USEITHREADS'
[Sun Aug 30 11:45:40.519047 2015] [authz_core:debug] [pid 29245:tid 140013752477440] mod_authz_core.c(835): [client 127.0.0.1:60851] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE, close
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.181446 2015] [authz_core:debug] [pid 29262:tid 140013635360512] mod_authz_core.c(835): [client 127.0.0.1:38957] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE, close
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.187726 2015] [authz_core:debug] [pid 29262:tid 140013626967808] mod_authz_core.c(835): [client 127.0.0.1:38959] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE, close
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.193109 2015] [authz_core:debug] [pid 29262:tid 140013643753216] mod_authz_core.c(835): [client 127.0.0.1:38961] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE, close
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.198394 2015] [authz_core:debug] [pid 29262:tid 140013652145920] mod_authz_core.c(835): [client 127.0.0.1:38963] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Keep-Alive: 300
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: Keep-Alive, TE
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.253331 2015] [authz_core:debug] [pid 29262:tid 140013735692032] mod_authz_core.c(835): [client 127.0.0.1:38965] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] a new request resetting the input filter state
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[P]
[  debug] filter read:
[OST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.257597 2015] [authz_core:debug] [pid 29262:tid 140013618575104] mod_authz_core.c(835): [client 127.0.0.1:38965] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] a new request resetting the input filter state
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[P]
[  debug] filter read:
[OST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.261831 2015] [authz_core:debug] [pid 29262:tid 140013744084736] mod_authz_core.c(835): [client 127.0.0.1:38965] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] a new request resetting the input filter state
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[P]
[  debug] filter read:
[OST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.266079 2015] [authz_core:debug] [pid 29262:tid 140013610182400] mod_authz_core.c(835): [client 127.0.0.1:38965] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[  debug] -------------------- input filter called ---------------------
[  debug] a new request resetting the input filter state
[  debug] -------------------- input filter called ---------------------
[  debug] -------------------- input filter called ---------------------
[  debug] filter context init
[  debug] filter read:
[POST /TestFilter__in_bbs_inject_header HTTP/1.1
]
[  debug] queued header [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-My-Protocol: POST-IT
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[TE: deflate,gzip;q=0.3
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Connection: TE, close
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Host: localhost.localdomain:8558
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[User-Agent: libwww-perl/6.13
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Length: 41
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[Content-Type: application/x-www-form-urlencoded
]
[  debug] -------------------- input filter called ---------------------
[  debug] filter read:
[
]
[  debug] END of original HTTP Headers
[  debug] queued header [X-Extra-Header3: Value 3
]
[  debug] queued header [X-Extra-Header2: Value 2
]
[  debug] queued header [
]
[  debug] injected header: [X-Extra-Header3: Value 3
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [X-Extra-Header2: Value 2
]
[  debug] -------------------- input filter called ---------------------
[  debug] injected header: [
]
[Sun Aug 30 11:45:41.272066 2015] [authz_core:debug] [pid 29262:tid 140013601789696] mod_authz_core.c(835): [client 127.0.0.1:38967] AH01628: authorization result: granted (no directives)
[  debug] -------------------- input filter called ---------------------
[  debug] passing the body through unmodified
[Sun Aug 30 11:45:41.378692 2015] [perl:info] [pid 29231:tid 140013969753984] Child process pid=29231 is exiting
[Sun Aug 30 11:45:41.378853 2015] [perl:info] [pid 29231:tid 140013969753984] Child process pid=29231 is exiting - server push
[Sun Aug 30 11:45:41.378846 2015] [perl:info] [pid 29262:tid 140013969753984] Child process pid=29262 is exiting
END in modperl_extra.pl, pid=29231
[Sun Aug 30 11:45:41.379037 2015] [perl:info] [pid 29262:tid 140013969753984] Child process pid=29262 is exiting - server push
END in modperl_extra.pl, pid=29262
[Sun Aug 30 11:45:41.383028 2015] [perl:info] [pid 29245:tid 140013969753984] Child process pid=29245 is exiting
[Sun Aug 30 11:45:41.383164 2015] [perl:info] [pid 29245:tid 140013969753984] Child process pid=29245 is exiting - server push
END in modperl_extra.pl, pid=29245
END in modperl_extra.pl, pid=29231
END in modperl_extra.pl, pid=29262
END in modperl_extra.pl, pid=29245
[Sun Aug 30 11:45:42.722196 2015] [core:info] [pid 29227:tid 140013969753984] AH00096: removed PID file /usr/local/src/httpd/mod_perl-2.0.9/t/logs/httpd.pid (pid=29227)
[Sun Aug 30 11:45:42.722225 2015] [mpm_event:notice] [pid 29227:tid 140013969753984] 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=29227
END in modperl_extra.pl, pid=29227