-------------8<---------- Start Bug Report ------------8<----------
1. Problem Description:
With Apache 2.1 (not 2.0) three sub-tests fail in
t/apache/content_length_header. Both Apache 2.1 and
mod_perl 2.0 were drawn from cvs within an hour of this
message, specifically:
-rw-r--r-- 1 root root 4484184 Sep 2 04:17 httpd-2.1.tar.bz2
-rw-r--r-- 1 root root 1171580 Sep 2 04:31 modperl-2.0.tar.bz2
Times are HST (GMT+10).
NOTE: On Thursday, August 26, this same test suite was
successful, therefore something changed between then and now
to cause it (be it me or thee).
NOTE-NOTE: Rather _current_ software (see configuration); note
gcc-3.4.1.
-- make test console log snips ---
/home/test/perl/5.8.5-apache2.1/bin/perl -Iblib/arch -Iblib/lib \
t/TEST -clean
[warning] setting ulimit to allow core files
ulimit -c
unlimited; /home/test/perl/5.8.5-apache2.1/bin/perl
/usr/src/sorcery/modperl-2.0/t/TEST
-clean
APACHE_TEST_GROUP= APACHE_TEST_HTTPD= APACHE_TEST_PORT= APACHE_TEST_USER=test
APACHE_TEST_APXS= \
/home/test/perl/5.8.5-apache2.1/bin/perl -Iblib/arch -Iblib/lib \
t/TEST -bugreport -verbose=0
[warning] setting ulimit to allow core files
ulimit -c
unlimited; /home/test/perl/5.8.5-apache2.1/bin/perl
/usr/src/sorcery/modperl-2.0/t/TEST
-bugreport -verbose=0
[warning] root mode: changing the files ownership to 'test' (111:500)
[warning] testing whether 'test' is able to
-rwx /usr/src/sorcery/modperl-2.0/t
"/home/test/perl/5.8.5-apache2.1/bin/perl"
-Mlib=/usr/src/sorcery/modperl-2.0/Apache-Test/lib -MApache::TestRun -e 'eval
{ Apache::TestRun::run_root_fs_test(111, 500,
q[/usr/src/sorcery/modperl-2.0/t]) }';
[warning] result: OK
[warning] the client side drops 'root' permissions and becomes 'test'
/home/test/usr/apache2.1/sbin/httpd -d /usr/src/sorcery/modperl-2.0/t
-f /usr/src/sorcery/modperl-2.0/t/conf/httpd.conf -D APACHE2 -D
PERL_USEITHREADS
using Apache/2.1.0-dev (prefork MPM)
waiting 300 seconds for server to start: .[Thu Sep 02 04:42:35 2004] [info] 25
Apache:: modules loaded
[Thu Sep 02 04:42:35 2004] [info] 7 APR:: modules loaded
[Thu Sep 02 04:42:35 2004] [info] base server + 22 vhosts ready to run tests
...................................
waiting 300 seconds for server to start: ok (waited 35 secs)
server localhost:8529 started
]
...
server localhost:8555 listening (TestDirective::perlloadmodule6)
t/apache/add_config.....................ok
t/apache/cgihandler.....................ok
t/apache/conftree.......................ok
t/apache/constants......................ok
t/apache/content_length_header..........# Failed test 2 in
t/apache/content_length_header.t at line 44
# Failed test 5 in t/apache/content_length_header.t at line 57
# Failed test 17 in t/apache/content_length_header.t at line 57 fail #2
FAILED tests 2, 5, 17
Failed 3/27 tests, 88.89% okay
t/apache/discard_rbody..................ok
...
2. Used Components and their Configuration:
*** mod_perl version 1.9917
*** using /usr/src/sorcery/modperl-2.0/lib/Apache/BuildConfig.pm
*** Makefile.PL options:
MP_APR_LIB => aprext
MP_APXS => /home/test/usr/apache2.1/sbin/apxs
MP_COMPAT_1X => 1
MP_GENERATE_XS => 1
MP_LIBNAME => mod_perl
MP_USE_DSO => 1
MP_USE_STATIC => 1
*** /home/test/usr/apache2.1/sbin/httpd -V
Server version: Apache/2.1.0-dev
Server built: Sep 2 2004 04:37:55
Server's Module Magic Number: 20040425:1
Architecture: 32-bit
Server MPM: Prefork
threaded: no
forked: yes (variable process count)
Server compiled with....
-D APACHE_MPM_DIR="server/mpm/prefork"
-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 HTTPD_ROOT="/home/test/usr/apache2.1"
-D SUEXEC_BIN="/home/test/usr/apache2.1/bin/suexec"
-D DEFAULT_PIDLOG="/home/test/var/run/httpd.pid"
-D DEFAULT_SCOREBOARD="logs/apache_runtime_status"
-D DEFAULT_LOCKFILE="/home/test/var/run/accept.lock"
-D DEFAULT_ERRORLOG="logs/error_log"
-D AP_TYPES_CONFIG_FILE="/home/test/httpd/2.1/conf/mime.types"
-D SERVER_CONFIG_FILE="/home/test/httpd/2.1/conf/httpd.conf"
*** (apr|apu)-config linking info
-L/home/test/usr/apache2.1/lib -lapr-1 -lrt -lcrypt -lpthread -ldl
-L/home/test/usr/apache2.1/lib -laprutil-1 -ldb-4.2 -lexpat
*** /home/test/perl/5.8.5-apache2.1/bin/perl -V
Summary of my perl5 (revision 5 version 8 subversion 5) configuration:
Platform:
osname=linux, osvers=2.6.8.1, archname=i686-linux-thread-multi
uname='linux cathy.beaucox.com 2.6.8.1 #2 sun aug 15 23:50:50 hst 2004
i686 unknown unknown gnulinux '
config_args='-Dprefix=/home/test/perl/5.8.5-apache2.1 -d -e -Dusethreads
-Duseshrplib'
hint=recommended, useposix=true, d_sigaction=define
usethreads=define use5005threads=undef useithreads=define
usemultiplicity=define
useperlio=define d_sfio=undef uselargefiles=define usesocks=undef
use64bitint=undef use64bitall=undef uselongdouble=undef
usemymalloc=n, bincompat5005=undef
Compiler:
cc='cc', ccflags ='-D_REENTRANT -D_GNU_SOURCE -DTHREADS_HAVE_PIDS
-fno-strict-aliasing -pipe -I/usr/local/include -D_LARGEFILE_SOURCE
-D_FILE_OFFSET_BITS=64',
optimize='-O2',
cppflags='-D_REENTRANT -D_GNU_SOURCE -DTHREADS_HAVE_PIDS
-fno-strict-aliasing -pipe -I/usr/local/include'
ccversion='', gccversion='3.4.1', gccosandvers=''
intsize=4, longsize=4, ptrsize=4, doublesize=8, byteorder=1234
d_longlong=define, longlongsize=8, d_longdbl=define, longdblsize=12
ivtype='long', ivsize=4, nvtype='double', nvsize=8, Off_t='off_t',
lseeksize=8
alignbytes=4, prototype=define
Linker and Libraries:
ld='cc', ldflags =' -L/usr/local/lib'
libpth=/usr/local/lib /lib /usr/lib
libs=-lnsl -ldb -ldl -lm -lcrypt -lutil -lpthread -lc
perllibs=-lnsl -ldl -lm -lcrypt -lutil -lpthread -lc
libc=/lib/libc-2.3.3.so, so=so, useshrplib=true, libperl=libperl.so
gnulibc_version='2.3.3'
Dynamic Linking:
dlsrc=dl_dlopen.xs, dlext=so, d_dlsymun=undef, ccdlflags='-Wl,-E
-Wl,-rpath,/home/test/perl/5.8.5-apache2.1/lib/5.8.5/i686-linux-thread-multi/CORE'
cccdlflags='-fpic', lddlflags='-shared -L/usr/local/lib'
Characteristics of this binary (from libperl):
Compile-time options: MULTIPLICITY USE_ITHREADS USE_LARGE_FILES
PERL_IMPLICIT_CONTEXT
Built under linux
Compiled at Aug 26 2004 12:03:10
%ENV:
PERLS_BASE_DIR="/usr/perl"
PERL_BIN="/usr/bin"
PERL_DIR="/usr"
PERL_LIB="/usr/lib/perl5"
PERL_LWP_USE_HTTP_10="1"
PERL_PERL="/usr/bin/perl"
PERL_VERSION="5.8.5"
PERL_VERSION_LIB="/usr/lib/perl5/5.8.5"
PERL_VERSION_SITE_PERL="/usr/lib/perl5/site_perl/5.8.5"
@INC:
/home/test/perl/5.8.5-apache2.1/lib/5.8.5/i686-linux-thread-multi
/home/test/perl/5.8.5-apache2.1/lib/5.8.5
/home/test/perl/5.8.5-apache2.1/lib/site_perl/5.8.5/i686-linux-thread-multi
/home/test/perl/5.8.5-apache2.1/lib/site_perl/5.8.5
/home/test/perl/5.8.5-apache2.1/lib/site_perl
.
*** Packages of interest status:
Apache::Request: -
CGI : 3.05
LWP : 5.800
mod_perl : 1.9917
3. This is the core dump trace: (if you get a core dump):
[CORE TRACE COMES HERE]
This report was generated by t/REPORT on Thu Sep 2 14:48:36 2004 GMT.
4. t/logs/error_log
[Thu Sep 02 04:42:36 2004] [info] Init: Seeding PRNG with 0 bytes of entropy
[Thu Sep 02 04:42:36 2004] [info] Init: Generating temporary RSA private keys
(512/1024 bits)
[Thu Sep 02 04:42:36 2004] [info] Init: Generating temporary DH parameters
(512/1024 bits)
[Thu Sep 02 04:42:36 2004] [warn] Init: Session Cache is not configured [hint:
SSLSessionCache]
[Thu Sep 02 04:42:36 2004] [info] Init: Initializing (virtual) servers for SSL
[Thu Sep 02 04:42:36 2004] [info] Server: Apache/2.1.0-dev, Interface:
mod_ssl/2.1.0-dev, Library: OpenSSL/0.9.7d
END in modperl_extra.pl, pid=7046
[Thu Sep 02 04:42:39 2004] [info] Init: Seeding PRNG with 0 bytes of entropy
[Thu Sep 02 04:42:39 2004] [info] Init: Generating temporary RSA private keys
(512/1024 bits)
[Thu Sep 02 04:42:39 2004] [info] Init: Generating temporary DH parameters
(512/1024 bits)
[Thu Sep 02 04:42:39 2004] [info] Init: Initializing (virtual) servers for SSL
[Thu Sep 02 04:42:39 2004] [info] Server: Apache/2.1.0-dev, Interface:
mod_ssl/2.1.0-dev, Library: OpenSSL/0.9.7d
[Thu Sep 02 04:42:39 2004] [notice] Digest: generating secret for digest
authentication ...
[Thu Sep 02 04:43:10 2004] [notice] Digest: done
[Thu Sep 02 04:43:10 2004] [notice] Embperl/2.0rc1 Apache/2.1.0-dev (Unix)
mod_perl/1.99_17-dev Perl/v5.8.5 world domination series/2.0
mod_ssl/2.1.0-dev OpenSSL/0.9.7d DAV/2 configured -- resuming normal
operations
[Thu Sep 02 04:43:10 2004] [info] Server built: Sep 2 2004 04:37:55
[Thu Sep 02 04:43:10 2004] [debug] prefork.c(976): AcceptMutex: sysvsem
(default: sysvsem)
[Thu Sep 02 04:43:39 2004] [info] [client 127.0.0.1] TestAPI::aplog test in
progress
This log message comes with no header
*** The following warn entry is expected and harmless ***
[Thu Sep 02 04:43:39 2004]
[debug] /usr/src/sorcery/modperl-2.0/t/response/TestAPI/aplog.pm(49):
log_serror test ok
*** The following warn entry is expected and harmless ***
[Thu Sep 02 04:43:39 2004]
[debug] /usr/src/sorcery/modperl-2.0/t/response/TestAPI/aplog.pm(53):
(20014)Internal error: log_serror test 2 ok
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:43:39 2004] [crit] [client 127.0.0.1] (20007)No time was
provided and one was required.: log_rerror test ok
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:43:39 2004] [error] $r->log_error test ok
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:43:39 2004] [error] $s->log_error test ok
[Thu Sep 02 04:43:39 2004]
[debug] /usr/src/sorcery/modperl-2.0/t/response/TestAPI/aplog.pm(77):
TestAPI::aplog test done
[Thu Sep 02 04:43:39 2004] [warn] ApacheApache->warn test ok
[Thu Sep 02 04:43:39 2004] [warn] $s->warn test ok
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:02 2004] [error] Apache::log_error test ok
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] APR::Socket::recv: (11)
Resource temporarily unavailable
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 132
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] Undefined subroutine
&TestError::runtime::no_such_func called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 126.\n
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] APR::Socket::recv: (11)
Resource temporarily unavailable
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
132\n\tTestError::runtime::mp_error('APR::Socket=SCALAR(0x95e1fd0)') called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
51\n\tTestError::runtime::die_hook_confess_mp_error('Apache::RequestRec=SCALAR(0x95dff5c)',
'APR::Socket=SCALAR(0x95e1fd0)') called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
30\n\tTestError::runtime::handler('Apache::RequestRec=SCALAR(0x95dff5c)')
called at -e line 0\n\teval {...} called at -e line 0\n
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] Undefined subroutine
&TestError::runtime::no_such_func called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 126.\n
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
126\n\tTestError::runtime::non_mp_error('APR::Socket=SCALAR(0x962f028)')
called at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
58\n\tTestError::runtime::die_hook_confess_non_mp_error('Apache::RequestRec=SCALAR(0x962b36c)',
'APR::Socket=SCALAR(0x962f028)') called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line
30\n\tTestError::runtime::handler('Apache::RequestRec=SCALAR(0x962b36c)')
called at -e line 0\n\teval {...} called at -e line 0\n
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] custom die hook:
APR::Socket::recv: (11) Resource temporarily unavailable
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 132
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 63.\n
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:40 2004] [error] [client 127.0.0.1] custom die hook:
Undefined subroutine &TestError::runtime::no_such_func called
at /usr/src/sorcery/modperl-2.0/t/response/TestError/runtime.pm line 126.\n
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:45:41 2004] [error] [client 127.0.0.1] failed to resolve
handler `TestError::syntax': syntax error
at /usr/src/sorcery/modperl-2.0/t/response/TestError/syntax.pm line 17, near
"\\;"\nCompilation failed in require at (eval 149) line 3.\n
[Thu Sep 02 04:45:46 2004] [debug] mod_deflate.c(805): [client 127.0.0.1]
Zlib: Inflated 54 to 59 : URL /TestFilter__both_str_req_mix
[Thu Sep 02 04:45:46 2004] [debug] mod_deflate.c(503): [client 127.0.0.1]
Zlib: Compressed 20 to 20 : URL /TestFilter__both_str_req_mix
*** The following 2 error entries are expected and harmless ***
[Thu Sep 02 04:45:58 2004] [error] [client 127.0.0.1] This filter must die
at /usr/src/sorcery/modperl-2.0/t/filter/TestFilter/in_error.pm line 26.\n
This filter must die
at /usr/src/sorcery/modperl-2.0/t/filter/TestFilter/in_error.pm line 26.
[Thu Sep 02 04:45:58 2004] [error] [client 127.0.0.1] Apache::RequestIO::read:
(500) Unknown error 500
at /usr/src/sorcery/modperl-2.0/t/filter/TestFilter/in_error.pm line 41
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:46:59 2004] [error] [client 127.0.0.1] File does not
exist: /usr/src/sorcery/modperl-2.0/t/htdocs/nope
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:47:42 2004] [error] [client 127.0.0.1] need
AuthName: /TestModperl__setauth
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:47:46 2004] [error] [client 127.0.0.1] File does not
exist: /usr/src/sorcery/modperl-2.0/t/htdocs/TestModperl__status
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:47:46 2004] [error] [client 127.0.0.1] Died
at /usr/src/sorcery/modperl-2.0/t/response/TestModperl/status.pm line 24.\n
*** The following error entry is expected and harmless ***
Argument "foobar" isn't numeric.
*** The following error entry is expected and harmless ***
Argument "foo9bar" isn't numeric.
Apache::Reload: Checking mtime of Apache/Reload/Test.pm
Subroutine run redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 7.
Subroutine simple redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 13.
Subroutine prototype redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 15.
Apache::Reload: process 9082 reloading Apache/Reload/Test.pm
Apache::Reload: Checking mtime of Apache/Reload/Test.pm
Subroutine run redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 7.
Subroutine simple redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 13.
Prototype mismatch: sub Apache::Reload::Test::prototype ($) vs ($$)
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 15.
Subroutine prototype redefined
at /usr/src/sorcery/modperl-2.0/t/lib/Apache/Reload/Test.pm line 15.
Apache::Reload: process 9082 reloading Apache/Reload/Test.pm
*** The following error entry is expected and harmless ***
[Thu Sep 02 04:48:30 2004] [error] [client 127.0.0.1] user stas:
authentication failure for "/": Password Mismatch
[Thu Sep 02 04:48:34 2004] [info] Child process pid=7512 is exiting
END in modperl_extra.pl, pid=7512
[Thu Sep 02 04:48:34 2004] [info] Child process pid=7513 is exiting
[Thu Sep 02 04:48:34 2004] [info] Child process pid=9082 is exiting
END in modperl_extra.pl, pid=9082
END in TestModperl::print, pid=7513
END in modperl_extra.pl, pid=7513
[Thu Sep 02 04:48:34 2004] [warn] child process 7513 still did not exit,
sending a SIGTERM
[Thu Sep 02 04:48:34 2004] [info] removed PID
file /usr/src/sorcery/modperl-2.0/t/logs/httpd.pid (pid=7242)
[Thu Sep 02 04:48:34 2004] [notice] caught SIGTERM, shutting down
END in modperl_extra.pl, pid=7242
-------------8<---------- End Bug Report --------------8<----------
--
Report problems: http://perl.apache.org/bugs/
Mail list info: http://perl.apache.org/maillist/modperl.html
List etiquette: http://perl.apache.org/maillist/email-etiquette.html
