Attachment 38121 Details for Bug 65725 – LogLevel_trace8

LogLevel_trace8

loglevel_trace8_ASF.txt (text/plain), 17.14 KB, created by farhanz on 2021-12-06 05:50:30 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: farhanz

Created: 2021-12-06 05:50:30 UTC

Size: 17.14 KB

patch

obsolete

>=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2021.12.06 10:56:56 =~=~=~=~=~=~=~=~=~=~=~=
>/home/username# >/var/log/apache2/error_log
>/home/username#        tail -f /var/log/apache2/error_log
>[Mon Dec 06 05:28:05.715359 2021] [mpm_event:trace7] [pid 26950:tid 2652828592] event.c(1764): polled with num=1 exit=0/0 conns=1 queues_timeout=11052858 timers_timeout=-1638790085715349
>[Mon Dec 06 05:28:05.715736 2021] [mpm_event:trace6] [pid 26950:tid 2652828592] event.c(1670): connections: 1 (clogged: 0 write-completion: 0 keep-alive: 0 lingering: 0 suspended: 0)
>[Mon Dec 06 05:28:05.715782 2021] [mpm_event:trace7] [pid 26950:tid 2652828592] event.c(1744): polling with timeout=11053000 queues_timeout=11052428 timers_timeout=-1638790085715779
>[Mon Dec 06 05:28:05.715914 2021] [ssl:info] [pid 26950:tid 2728362928] [client client_ip:43468] AH01964: Connection to child 7 established (server idrac:443)
>[Mon Dec 06 05:28:05.716327 2021] [ssl:trace2] [pid 26950:tid 2728362928] ssl_engine_rand.c(125): Server: Seeding PRNG with 256 bytes of entropy
>[Mon Dec 06 05:28:05.716456 2021] [ssl:trace6] [pid 26950:tid 2728362928] ssl_engine_io.c(2237): [client client_ip:43468] Enabling non-blocking writes
>[Mon Dec 06 05:28:05.716539 2021] [ssl:trace3] [pid 26950:tid 2728362928] ssl_engine_kernel.c(2203): [client client_ip:43468] OpenSSL: Handshake: start
>[Mon Dec 06 05:28:05.716662 2021] [ssl:trace3] [pid 26950:tid 2728362928] ssl_engine_kernel.c(2211): [client client_ip:43468] OpenSSL: Loop: before SSL initialization
>[Mon Dec 06 05:28:05.716739 2021] [ssl:trace4] [pid 26950:tid 2728362928] ssl_engine_io.c(2365): [client client_ip:43468] OpenSSL: read 5/5 bytes from BIO#9d609570 [mem: 9d62a013] (BIO dump follows)
>
>[Mon Dec 06 05:28:06.617163 2021] [core:trace5] [pid 26950:tid 2728362928] protocol.c(711): [client client_ip:43468] Request received from client: POST /wsman HTTP/1.1
>[Mon Dec 06 05:28:06.617285 2021] [setenvif:trace2] [pid 26950:tid 2728362928] mod_setenvif.c(630): [client client_ip:43468] Setting HTTP_AUTHORIZATION
>[Mon Dec 06 05:28:06.617328 2021] [ssl:debug] [pid 26950:tid 2728362928] ssl_engine_kernel.c(415): [client client_ip:43468] AH02034: Initial (No.1) HTTPS request received for child 7 (server idrac:443)
>[Mon Dec 06 05:28:06.617363 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(435): [client client_ip:43468] Headers received from client:
>[Mon Dec 06 05:28:06.617383 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Authorization: Basic cm9vdDpkaGNwQMw==
>[Mon Dec 06 05:28:06.617401 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   User-Agent: curl/7.29.0
>[Mon Dec 06 05:28:06.617417 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Host: server_ip
>[Mon Dec 06 05:28:06.617453 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Accept: */*
>[Mon Dec 06 05:28:06.617472 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Content-Type: application/soap+xml;charset=UTF-8
>[Mon Dec 06 05:28:06.617488 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Content-Length: 1455
>[Mon Dec 06 05:28:06.617503 2021] [http:trace4] [pid 26950:tid 2728362928] http_request.c(438): [client client_ip:43468]   Expect: 100-continue
>
>[Mon Dec 06 05:28:06.618671 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(996): [client client_ip:43468] AH02527: fcgi_authz_check()
>[Mon Dec 06 05:28:06.618720 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(127): [client client_ip:43468] AH02496: name fcgi-auth, backend fcgi://127.0.0.1:4300/, host 127.0.0.1, port 4300, first address 127.0.0.1:4300, NZ
>[Mon Dec 06 05:28:06.618751 2021] [authz_core:debug] [pid 26950:tid 2728362928] mod_authz_core.c(815): [client client_ip:43468] AH01626: authorization result of Require fcgi-auth : denied (no authenticated user yet)
>[Mon Dec 06 05:28:06.618772 2021] [authz_core:debug] [pid 26950:tid 2728362928] mod_authz_core.c(815): [client client_ip:43468] AH01626: authorization result of <RequireAny>: denied (no authenticated user yet)
>[Mon Dec 06 05:28:06.618804 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(819): [client client_ip:43468] AH02516: fcgi_check_authn, prov fcgi-auth, authoritative yes, require-basic no, user expr? yes type Basic
>[Mon Dec 06 05:28:06.618832 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(127): [client client_ip:43468] AH02496: name fcgi-auth, backend fcgi://127.0.0.1:4300/, host 127.0.0.1, port 4300, first address 127.0.0.1:4300, NZ
>[Mon Dec 06 05:28:06.619571 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_AUTHORIZATION': 'Basic cm9vdDpkaGNwQDEyMw=='
>[Mon Dec 06 05:28:06.619626 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SCRIPT_URL': '/wsman'
>[Mon Dec 06 05:28:06.619646 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SCRIPT_URI': 'https://server_ip/wsman'
>[Mon Dec 06 05:28:06.619664 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_USER_AGENT': 'curl/7.29.0'
>[Mon Dec 06 05:28:06.619681 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_HOST': 'server_ip'
>[Mon Dec 06 05:28:06.619697 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_ACCEPT': '*/*'
>[Mon Dec 06 05:28:06.619716 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'CONTENT_TYPE': 'application/soap+xml;charset=UTF-8'
>[Mon Dec 06 05:28:06.619733 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_EXPECT': '100-continue'
>[Mon Dec 06 05:28:06.619749 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
>[Mon Dec 06 05:28:06.619793 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'LD_LIBRARY_PATH': '/usr/lib'
>[Mon Dec 06 05:28:06.619815 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_SIGNATURE': ''
>[Mon Dec 06 05:28:06.620283 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_SOFTWARE': 'Apache'
>[Mon Dec 06 05:28:06.620310 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_NAME': 'server_ip'
>[Mon Dec 06 05:28:06.620328 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_ADDR': 'server_ip'
>[Mon Dec 06 05:28:06.620344 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_PORT': '443'
>[Mon Dec 06 05:28:06.620359 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REMOTE_ADDR': 'client_ip'
>[Mon Dec 06 05:28:06.620374 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'DOCUMENT_username': '/usr/local/www'
>[Mon Dec 06 05:28:06.620390 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REQUEST_SCHEME': 'https'
>[Mon Dec 06 05:28:06.620407 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'CONTEXT_PREFIX': ''
>[Mon Dec 06 05:28:06.620422 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'CONTEXT_DOCUMENT_username': '/usr/local/www'
>[Mon Dec 06 05:28:06.620437 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_ADMIN': '[no address given]'
>[Mon Dec 06 05:28:06.620453 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SCRIPT_FILENAME': '/usr/local/www/wsman'
>[Mon Dec 06 05:28:06.620470 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REMOTE_PORT': '43468'
>[Mon Dec 06 05:28:06.620647 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REMOTE_USER': 'username'
>[Mon Dec 06 05:28:06.620811 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'AUTH_TYPE': 'Basic'
>[Mon Dec 06 05:28:06.620912 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'GATEWAY_INTERFACE': 'CGI/1.1'
>[Mon Dec 06 05:28:06.621967 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'SERVER_PROTOCOL': 'HTTP/1.1'
>[Mon Dec 06 05:28:06.622025 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REQUEST_METHOD': 'POST'
>[Mon Dec 06 05:28:06.622047 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'QUERY_STRING': ''
>[Mon Dec 06 05:28:06.622066 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REQUEST_URI': '/wsman'
>[Mon Dec 06 05:28:06.622083 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'FCGI_ROLE': 'AUTHORIZER'
>[Mon Dec 06 05:28:06.622098 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'FCGI_APACHE_ROLE': 'AUTHENTICATOR'
>[Mon Dec 06 05:28:06.622138 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'REMOTE_PASSWD': 'XXXXXXXX'
>[Mon Dec 06 05:28:06.622158 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(316): [client client_ip:43468] send_environment: 'HTTP_CONNECTION': 'close'
>[Mon Dec 06 05:28:06.622186 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(351): [client client_ip:43468] AH02500: required len for encoding envvars: 894, 34/34 elems processed so far
>[Mon Dec 06 05:28:06.766740 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_script.c(568): [client client_ip:43468] Headers from script 'wsman':
>[Mon Dec 06 05:28:06.766814 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_script.c(571): [client client_ip:43468]   Variable-REQUEST_ORIGIN: external
>[Mon Dec 06 05:28:06.766844 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_script.c(571): [client client_ip:43468]   Variable-AUTHNZ_USER: username
>[Mon Dec 06 05:28:06.766865 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_script.c(571): [client client_ip:43468]   Variable-USER_PRIV: 511
>[Mon Dec 06 05:28:06.766883 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_script.c(571): [client client_ip:43468]   Status: 200
>[Mon Dec 06 05:28:06.766901 2021] [authnz_fcgi:trace1] [pid 26950:tid 2728362928] util_script.c(650): [client client_ip:43468] Status line from script 'wsman': 200
>[Mon Dec 06 05:28:06.766928 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(569): [client client_ip:43468] AH02504: handle_response: script header parsing -> 0/200
>[Mon Dec 06 05:28:06.767071 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(772): [client client_ip:43468] AH02515: req_rsp: Received HTTP status 200
>[Mon Dec 06 05:28:06.767111 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(693): [client client_ip:43468] moving Variable-REQUEST_ORIGIN->external
>[Mon Dec 06 05:28:06.767133 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(693): [client client_ip:43468] moving Variable-AUTHNZ_USER->username
>[Mon Dec 06 05:28:06.767150 2021] [authnz_fcgi:trace2] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(693): [client client_ip:43468] moving Variable-USER_PRIV->511
>[Mon Dec 06 05:28:06.767173 2021] [authnz_fcgi:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/fcgi-auth.conf:36 gave: username
>[Mon Dec 06 05:28:06.767193 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(862): [client client_ip:43468] AH02519: fcgi_check_authn: Setting user to 'username'
>[Mon Dec 06 05:28:06.767219 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(996): [client client_ip:43468] AH02527: fcgi_authz_check()
>[Mon Dec 06 05:28:06.767239 2021] [authnz_fcgi:debug] [pid 26950:tid 2728362928] mod_authnz_fcgi.c(127): [client client_ip:43468] AH02496: name fcgi-auth, backend fcgi://127.0.0.1:4300/, host 127.0.0.1, port 4300, first address 127.0.0.1:4300, NZ
>[Mon Dec 06 05:28:06.767268 2021] [authz_core:debug] [pid 26950:tid 2728362928] mod_authz_core.c(815): [client client_ip:43468] AH01626: authorization result of Require fcgi-auth : granted
>[Mon Dec 06 05:28:06.767287 2021] [authz_core:debug] [pid 26950:tid 2728362928] mod_authz_core.c(815): [client client_ip:43468] AH01626: authorization result of <RequireAny>: granted
>[Mon Dec 06 05:28:06.768159 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:149 gave: server_ip
>[Mon Dec 06 05:28:06.768224 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:150 gave: client_ip:43468
>[Mon Dec 06 05:28:06.768287 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:151 gave: client_ip:43468
>[Mon Dec 06 05:28:06.768317 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:152 gave: username
>[Mon Dec 06 05:28:06.768341 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:153 gave: 511
>[Mon Dec 06 05:28:06.768367 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(843): [client client_ip:43468] Evaluation of string expression from /etc/apache2/conf.d/03-vhosts.conf:154 gave: 
>
>[Mon Dec 06 05:28:08.861849 2021] [headers:trace2] [pid 26950:tid 2728362928] mod_headers.c(865): AH01502: headers: ap_headers_output_filter()
>[Mon Dec 06 05:28:08.861951 2021] [headers:trace4] [pid 26950:tid 2728362928] util_expr_eval.c(859): [client client_ip:43468] Evaluation of expression from /etc/apache2/conf.d/03-vhosts.conf:140 gave: 0
>[Mon Dec 06 05:28:08.862040 2021] [http:trace3] [pid 26950:tid 2728362928] http_filters.c(1126): [client client_ip:43468] Response sent with status 200, headers:
>[Mon Dec 06 05:28:08.862070 2021] [http:trace5] [pid 26950:tid 2728362928] http_filters.c(1135): [client client_ip:43468]   Date: Mon, 06 Dec 2021 11:28:06 GMT
>[Mon Dec 06 05:28:08.862089 2021] [http:trace5] [pid 26950:tid 2728362928] http_filters.c(1138): [client client_ip:43468]   Server: Apache
>[Mon Dec 06 05:28:08.862110 2021] [http:trace4] [pid 26950:tid 2728362928] http_filters.c(956): [client client_ip:43468]   X-Frame-Options: DENY
>[Mon Dec 06 05:28:08.862129 2021] [http:trace4] [pid 26950:tid 2728362928] http_filters.c(956): [client client_ip:43468]   Strict-Transport-Security: max-age=63072000; includeSubDomains; preload
>[Mon Dec 06 05:28:08.862146 2021] [http:trace4] [pid 26950:tid 2728362928] http_filters.c(956): [client client_ip:43468]   Content-Length: 4198
>[Mon Dec 06 05:28:08.862161 2021] [http:trace4] [pid 26950:tid 2728362928] http_filters.c(956): [client client_ip:43468]   Content-Type: application/soap+xml; charset=UTF-8
>[Mon Dec 06 05:28:08.862188 2021] [ssl:trace4] [pid 26950:tid 2728362928] ssl_engine_io.c(1817): [client client_ip:43468] coalesce: have 0 bytes, adding 241 more (buckets=1)
>[Mon Dec 06 05:28:08.862216 2021] [ssl:trace4] [pid 26950:tid 2728362928] ssl_engine_io.c(1880): [client client_ip:43468] coalesce: passing on 241 bytes
>[Mon Dec 06 05:28:08.862264 2021] [ssl:trace6] [pid 26950:tid 2728362928] ssl_engine_io.c(855): [client client_ip:43468] ssl_filter_write: 241 bytes
>[Mon Dec 06 05:28:08.862372 2021] [ssl:trace6] [pid 26950:tid 2728362928] ssl_engine_io.c(214): [client client_ip:43468] bio_filter_out_write: 270 bytes

Actions: View

Attachments on bug 65725: 38117 | 38118 | 38119 | 38120 | 38121