FAQ
1. Problem Description:

   t/hooks/authen_digest.t fails

sre@nta1.ntf.cftdev1 /ntfhome/local/src/mod_perl2_svn/mod_perl
2014-06-09 18:15:28 Mon $ t/TEST -verbose t/hooks/authen_digest.t
/ntfhome/local/bin/httpd -d /ntfhome/local/src/mod_perl2_svn/mod_perl/t -f /ntfhome/local/src/mod_perl2_svn/mod_perl/t/conf/httpd.conf -D APACHE2 -D PERL_USEITHREADS
using Apache/2.4.9 (event MPM)

waiting 300 seconds for server to start: .[Mon Jun 09 18:17:26.315156 2014] [perl:info] [pid 5358:tid 47146650739312] 6 Apache2:: modules loaded
[Mon Jun 09 18:17:26.315448 2014] [perl:info] [pid 5358:tid 47146650739312] 0 APR:: modules loaded
[Mon Jun 09 18:17:26.315584 2014] [perl:info] [pid 5358:tid 47146650739312] base server + 32 vhosts ready to run tests
........
waiting 300 seconds for server to start: ok (waited 7 secs)
server localhost:8529 started
server localhost:8530 listening (filter_out_apache)
server localhost:8531 listening (perlsections)
server localhost:8532 listening (inherit)
server localhost:8533 listening (TestModperl::perl_options)
server localhost:8534 listening (TestModperl::perl_options2)
server localhost:8535 listening (TestModperl::merge)
server localhost:8536 listening (TestModperl::setupenv)
server localhost:8537 listening (TestVhost::log)
server localhost:8538 listening (TestVhost::config)
server localhost:8539 listening (TestModules::proxy)
server localhost:8540 listening (TestDirective::perlcleanuphandler)
server localhost:8541 listening (TestUser::rewrite)
server localhost:8542 listening (TestProtocol::pseudo_http)
server localhost:8543 listening (TestProtocol::echo_filter)
server localhost:8544 listening (TestProtocol::echo_nonblock)
server localhost:8545 listening (TestProtocol::echo_bbs)
server localhost:8546 listening (TestProtocol::echo_timeout)
server localhost:8547 listening (TestProtocol::eliza)
server localhost:8548 listening (TestProtocol::echo_bbs2)
server localhost:8549 listening (TestProtocol::echo_block)
server localhost:8550 listening (TestPreConnection::note)
server localhost:8551 listening (TestHooks::hookrun)
server localhost:8552 listening (TestHooks::trans)
server localhost:8553 listening (TestHooks::startup)
server localhost:8554 listening (TestHooks::init)
server localhost:8555 listening (TestHooks::stacked_handlers2)
server localhost:8556 listening (TestFilter::both_str_con_add)
server localhost:8557 listening (TestFilter::in_str_msg)
server localhost:8558 listening (TestFilter::in_bbs_inject_header)
server localhost:8559 listening (TestFilter::in_bbs_msg)
server localhost:8560 listening (TestDirective::perlrequire)
server localhost:8561 listening (TestDirective::perlmodule)
server localhost:8562 listening (TestPerl::ithreads)
server localhost:8563 listening (TestPerl::ithreads3)
server localhost:8564 listening (TestAPI::add_config)
server localhost:8565 listening (TestDirective::perlloadmodule3)
server localhost:8566 listening (TestDirective::perlloadmodule4)
server localhost:8567 listening (TestDirective::perlloadmodule5)
server localhost:8568 listening (TestDirective::perlloadmodule6)
server localhost:8569 listening (TestHooks::push_handlers_anon)
t/hooks/authen_digest.t ..
1..7
# Running under perl version 5.020000 for linux
# Current time local: Mon Jun 9 18:17:35 2014
# Current time GMT: Tue Jun 10 01:17:35 2014
# Using Test.pm version 1.26
# Using Apache/Test.pm version 1.38
# testing : handler returned HTTP_OK
# expected: 200
# received: 200
ok 1
# response had no WWW-Authenticate header
ok 2
# testing : handler returned HTTP_UNAUTHORIZED
# expected: 401
# received: 401
ok 3
# response had a WWW-Authenticate header
not ok 4
# testing : response is using Digest authentication scheme
# expected: (?^:^Digest)
# received: undef
not ok 5
# testing : WWW-Authenticate header contains the proper realm
# expected: (?^:realm="Simple Digest")
# received: undef
not ok 6
# testing : WWW-Authenticate header contains a nonce
# expected: (?^:nonce=")
# received: undef
not ok 7
# Failed test 4 in t/hooks/authen_digest.t at line 36
# Failed test 5 in t/hooks/authen_digest.t at line 38
# Failed test 6 in t/hooks/authen_digest.t at line 42
# Failed test 7 in t/hooks/authen_digest.t at line 46
Failed 4/7 subtests

Test Summary Report
-------------------
t/hooks/authen_digest.t (Wstat: 0 Tests: 7 Failed: 4)
   Failed tests: 4-7
Files=1, Tests=7, 3 wallclock secs ( 0.04 usr 0.03 sys + 1.06 cusr 0.67 csys = 1.80 CPU)
Result: FAIL
Failed 1/1 test programs. 4/7 subtests failed.
[warning] server localhost:8529 shutdown
[warning] port 8529 still in use...
.........done
[ error] error running tests (please examine t/logs/error_log)

sre@nta1.ntf.cftdev1 /ntfhome/local/src/mod_perl2_svn/mod_perl
2014-06-09 18:17:52 Mon $ cat t/logs/error_log
********************************************************************************
*** This is a test for Apache2::ServerUtil::server_shutdown_cleanup_register ***
*** Following a line consisting only of * characters there should be a line ***
*** containing ***
*** "cleanup died: testing server_shutdown_cleanup_register". ***
*** The next line should then read ***
*** "done with server_shutdown_cleanup_register" ***
********************************************************************************
Apache2::ServerUtil: cleanup died: testing server_shutdown_cleanup_register
*** done with server_shutdown_cleanup_register ***
********************************************************************************
END in modperl_extra.pl, pid=5358
END in modperl_extra.pl, pid=5358
[Mon Jun 09 18:17:34.470318 2014] [mpm_event:notice] [pid 5394:tid 47146650739312] AH00489: Apache/2.4.9 (Unix) world domination series/2.0 mod_perl/2.0.9dev Perl/v5.20.0 configured -- resuming normal operations
[Mon Jun 09 18:17:34.470377 2014] [mpm_event:info] [pid 5394:tid 47146650739312] AH00490: Server built: Jun 4 2014 17:22:40
[Mon Jun 09 18:17:34.470441 2014] [core:notice] [pid 5394:tid 47146650739312] AH00094: Command line: '/ntfhome/local/bin/httpd -d /ntfhome/local/src/mod_perl2_svn/mod_perl/t -f /ntfhome/local/src/mod_perl2_svn/mod_perl/t/conf/httpd.conf -D APACHE2 -D PERL_USEITHREADS'
[Mon Jun 09 18:17:35.160030 2014] [authz_core:debug] [pid 5409:tid 1107544384] mod_authz_core.c(828): [client 127.0.0.1:58819] AH01628: authorization result: granted (no directives)
[Mon Jun 09 18:17:36.652324 2014] [authz_core:debug] [pid 5411:tid 1119811904] mod_authz_core.c(802): [client 127.0.0.1:58820] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
[Mon Jun 09 18:17:36.652994 2014] [authz_core:debug] [pid 5411:tid 1119811904] mod_authz_core.c(802): [client 127.0.0.1:58820] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
[Mon Jun 09 18:17:36.657262 2014] [authz_core:debug] [pid 5411:tid 1119811904] mod_authz_core.c(802): [client 127.0.0.1:58820] AH01626: authorization result of Require valid-user : granted
[Mon Jun 09 18:17:36.657285 2014] [authz_core:debug] [pid 5411:tid 1119811904] mod_authz_core.c(802): [client 127.0.0.1:58820] AH01626: authorization result of <RequireAny>: granted
[Mon Jun 09 18:17:36.663689 2014] [authz_core:debug] [pid 5411:tid 1130301760] mod_authz_core.c(802): [client 127.0.0.1:58821] AH01626: authorization result of Require valid-user : denied (no authenticated user yet)
[Mon Jun 09 18:17:36.663717 2014] [authz_core:debug] [pid 5411:tid 1130301760] mod_authz_core.c(802): [client 127.0.0.1:58821] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
[Mon Jun 09 18:17:38.118750 2014] [perl:info] [pid 5438:tid 47146650739312] Child process pid=5438 is exiting
[Mon Jun 09 18:17:38.119072 2014] [perl:info] [pid 5438:tid 47146650739312] Child process pid=5438 is exiting - server push
END in modperl_extra.pl, pid=5438
[Mon Jun 09 18:17:38.121286 2014] [perl:info] [pid 5411:tid 47146650739312] Child process pid=5411 is exiting
[Mon Jun 09 18:17:38.123255 2014] [perl:info] [pid 5411:tid 47146650739312] Child process pid=5411 is exiting - server push
END in modperl_extra.pl, pid=5411
[Mon Jun 09 18:17:38.122965 2014] [perl:info] [pid 5409:tid 47146650739312] Child process pid=5409 is exiting
[Mon Jun 09 18:17:38.131061 2014] [perl:info] [pid 5409:tid 47146650739312] Child process pid=5409 is exiting - server push
END in modperl_extra.pl, pid=5409
END in modperl_extra.pl, pid=5438
END in modperl_extra.pl, pid=5411
END in modperl_extra.pl, pid=5409
[Mon Jun 09 18:17:41.474895 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5409 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:41.477315 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5411 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:41.477391 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5438 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:43.474999 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5409 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:43.475065 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5411 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:43.475080 2014] [core:warn] [pid 5394:tid 47146650739312] AH00045: child process 5438 still did not exit, sending a SIGTERM
[Mon Jun 09 18:17:45.475830 2014] [core:info] [pid 5394:tid 47146650739312] AH00096: removed PID file /ntfhome/local/src/mod_perl2_svn/mod_perl/t/logs/httpd.pid (pid=5394)
[Mon Jun 09 18:17:45.475891 2014] [mpm_event:notice] [pid 5394:tid 47146650739312] 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=5394
END in modperl_extra.pl, pid=5394


2. Used Components and their Configuration:

*** mod_perl version 2.000009

*** using /ntfhome/local/src/mod_perl2_svn/mod_perl/lib/Apache2/BuildConfig.pm

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


*** /ntfhome/local/bin/httpd -V
Server version: Apache/2.4.9 (Unix)
Server built: Jun 4 2014 17:22:40
Server's Module Magic Number: 20120211:31
Server loaded: APR 1.5.0, APR-UTIL 1.5.3
Compiled using: APR 1.5.0, APR-UTIL 1.5.3
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="/ntfhome/local"
  -D SUEXEC_BIN="/ntfhome/local/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 /ntfhome/local/bin/httpd
  linux-vdso.so.1 => (0x00007fff342c6000)
  libpcre.so.1 => /ntfhome/local/lib/libpcre.so.1 (0x00002b8f457db000)
  libaprutil-1.so.0 => /ntfhome/local/lib/libaprutil-1.so.0 (0x00002b8f45a41000)
  libexpat.so.0 => /lib64/libexpat.so.0 (0x00002b8f45c71000)
  libapr-1.so.0 => /ntfhome/local/lib/libapr-1.so.0 (0x00002b8f45e93000)
  libuuid.so.1 => /lib64/libuuid.so.1 (0x00002b8f460c2000)
  librt.so.1 => /lib64/librt.so.1 (0x00002b8f462c7000)
  libcrypt.so.1 => /lib64/libcrypt.so.1 (0x00002b8f464d0000)
  libpthread.so.0 => /lib64/libpthread.so.0 (0x00002b8f46708000)
  libdl.so.2 => /lib64/libdl.so.2 (0x00002b8f46924000)
  libc.so.6 => /lib64/libc.so.6 (0x00002b8f46b28000)
  /lib64/ld-linux-x86-64.so.2 (0x00002b8f455bd000)


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

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



*** /ntfhome/software/perl/5.20.0/bin/perl -V
Summary of my perl5 (revision 5 version 20 subversion 0) configuration:

   Platform:
     osname=linux, osvers=2.6.18-308.0.0.0.1.el5xen, archname=x86_64-linux-thread-multi-ld
     uname='linux nta1.ntf.cftdev1.coresys.tmcs 2.6.18-308.0.0.0.1.el5xen #1 smp sat feb 25 16:26:29 est 2012 x86_64 x86_64 x86_64 gnulinux '
     config_args='-de -Dprefix=/ntfhome/software/perl/5.20.0 -Duse64bitall -Dusethreads -Dinc_version_list=none -Duselongdouble -Dusemorebits -Dotherlibdirs=/ntf/shared/ntf/lib/perl5:/ntf/shared/tmns/lib/perl:/ntf/shared/csweb/lib/perl -A ccflags=-fPIC'
     hint=recommended, useposix=true, d_sigaction=define
     useithreads=define, usemultiplicity=define
     use64bitint=define, use64bitall=define, uselongdouble=define
     usemymalloc=n, bincompat5005=undef
   Compiler:
     cc='cc', ccflags ='-D_REENTRANT -D_GNU_SOURCE -fPIC -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64',
     optimize='-O2',
     cppflags='-D_REENTRANT -D_GNU_SOURCE -fPIC -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include'
     ccversion='', gccversion='4.1.2 20080704 (Red Hat 4.1.2-52)', 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='long double', nvsize=16, Off_t='off_t', lseeksize=8
     alignbytes=16, prototype=define
   Linker and Libraries:
     ld='cc', ldflags =' -fstack-protector -L/usr/local/lib'
     libpth=/usr/local/lib /usr/lib /lib /lib64 /usr/lib64 /usr/local/lib64
     libs=-lnsl -lgdbm -ldb -ldl -lm -lcrypt -lutil -lpthread -lc
     perllibs=-lnsl -ldl -lm -lcrypt -lutil -lpthread -lc
     libc=libc-2.5.so, so=so, useshrplib=false, libperl=libperl.a
     gnulibc_version='2.5'
   Dynamic Linking:
     dlsrc=dl_dlopen.xs, dlext=so, d_dlsymun=undef, ccdlflags='-Wl,-E'
     cccdlflags='-fPIC', lddlflags='-shared -O2 -L/usr/local/lib -fstack-protector'


Characteristics of this binary (from libperl):
   Compile-time options: HAS_TIMES MULTIPLICITY PERLIO_LAYERS
                         PERL_DONT_CREATE_GVSV
                         PERL_HASH_FUNC_ONE_AT_A_TIME_HARD
                         PERL_IMPLICIT_CONTEXT PERL_MALLOC_WRAP
                         PERL_NEW_COPY_ON_WRITE PERL_PRESERVE_IVUV
                         USE_64_BIT_ALL USE_64_BIT_INT USE_ITHREADS
                         USE_LARGE_FILES USE_LOCALE USE_LOCALE_COLLATE
                         USE_LOCALE_CTYPE USE_LOCALE_NUMERIC USE_LONG_DOUBLE
                         USE_PERLIO USE_PERL_ATOF USE_REENTRANT_API
   Built under linux
   Compiled at Jun 6 2014 12:31:05
   %ENV:
     PERL5LIB="/nta/shared/conf/perl:/nta/shared/lib/perl:/nta/ntf/lib/site_perl"
     PERL_BASEDIR="/software/perl/5.8.4"
     PERL_BINDIR="/software/perl/5.8.4/bin"
     PERL_LWP_USE_HTTP_10="1"
     PERL_SITE_BINDIR="/software/perl/5.8.4/lib/perl5/bin"
     PERL_VERSION="5.8.4"
   @INC:
     /nta/shared/conf/perl
     /nta/shared/lib/perl
     /nta/ntf/lib/site_perl
     /ntfhome/software/perl/5.20.0/lib/site_perl/5.20.0/x86_64-linux-thread-multi-ld
     /ntfhome/software/perl/5.20.0/lib/site_perl/5.20.0
     /ntfhome/software/perl/5.20.0/lib/5.20.0/x86_64-linux-thread-multi-ld
     /ntfhome/software/perl/5.20.0/lib/5.20.0
     /ntf/shared/ntf/lib/perl5/x86_64-linux-thread-multi-ld
     /ntf/shared/ntf/lib/perl5
     /ntf/shared/tmns/lib/perl
     /ntf/shared/csweb/lib/perl
     .

*** Packages of interest status:

Apache2 : -
Apache2::Request : -
CGI : 3.65, 4.01
ExtUtils::MakeMaker: 6.98
LWP : 6.06
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 Tue Jun 10 01:18:53 2014 GMT.

Search Discussions

Related Discussions

Discussion Navigation
viewthread | post
Discussion Overview
groupmodperl @
categoriesmodperl, perl
postedJun 10, '14 at 1:31a
activeJun 10, '14 at 1:31a
posts1
users1
websiteperl.apache.org

1 user in discussion

Mark Hedges: 1 post

People

Translate

site design / logo © 2018 Grokbase