[jboss-jira] [JBoss JIRA] (WFLY-12736) Intermittent Session Termination in Wildfly 10

Thursday, 31 October 2019

Irfan Ali Dogar created WFLY-12736:
--------------------------------------

             Summary: Intermittent Session Termination in Wildfly 10
                 Key: WFLY-12736
                 URL: https://issues.jboss.org/browse/WFLY-12736
             Project: WildFly
          Issue Type: Bug
          Components: Web (Undertow)
    Affects Versions: 10.1.0.Final
         Environment: Wildfly 10.1.Final, Linux, Apache
            Reporter: Irfan Ali Dogar
            Assignee: Flavia Rainone

Hi All,

We are using Wildfly 10.0.1.Final in production and facing an issue with sessions, when we
generate our reports using Jasper or other tools after around 2 minutes session is
terminated. This happens only during report generation, we have looked at the code and
couldn't find any problematic area. To start with I have enabled debugging on undertow
and I can see user get's authenticated but after around two minutes the outcome
becomes NOT_ATTEMPTED, when this happens our custom authentication mechanism kicks in and
it sends user back to login page. Please see the logs from the server below:

2019-10-29 11:06:16,351 DEBUG [io.undertow.request] (default I/O-4) Matched default
handler path /messagebroker/amfspsecure

2019-10-29 11:06:16,351 DEBUG [io.undertow.request.security] (default task-51) Security
constraints for request /messagebroker/amfspsecure are
[SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[$SYSTEM_ROLE_LOGIN$]}]

2019-10-29 11:06:16,352 DEBUG [io.undertow.request.security] (default task-51)
Authenticating required for request HttpServerExchange{ POST /messagebroker/amfspsecure
request {X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[4842], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:16,352 DEBUG [io.undertow.request.security] (default task-51) Setting
authentication required for exchange HttpServerExchange{ POST /messagebroker/amfspsecure
request {X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[4842], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:16,352 DEBUG [io.undertow.request.security] (default task-51) Attempting
to authenticate HttpServerExchange{ POST /messagebroker/amfspsecure request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[4842], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}},
authentication required: true

2019-10-29 11:06:16,368 DEBUG [io.undertow.request.security] (default task-51)
Authenticated as dummy.user_login_postfix_SXl2qtNGf3OfyluZzQ90aIH9MV1ZXq1baqSb8mUD, roles
[Super Users, $SYSTEM_ROLE_LOGIN$, Super User (RSA 2nd Level Authentification),]

2019-10-29 11:06:16,369 DEBUG [io.undertow.request.security] (default task-51)
Authentication outcome was AUTHENTICATED with method
io.undertow.security.impl.CachedAuthenticatedSessionMechanism@3a039cf for
HttpServerExchange{ POST /messagebroker/amfspsecure request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[4842], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:06:16,369 DEBUG [io.undertow.request.security] (default task-51)
Authentication result was AUTHENTICATED for HttpServerExchange{ POST
/messagebroker/amfspsecure request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[4842], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:06:16,434 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
In Report Facade prepareParams method........................................

2019-10-29 11:06:16,557 WARN  [com.os.sp.enterprise.reportingengine.ejb.ReportingHelper]
(default task-51) Plan version number hasn't been added to template by report
generation

2019-10-29 11:06:16,583 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
In Report Facade: Params Prepared successfully

2019-10-29 11:06:16,583 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
In Reporting Facade callServiceGeneration method.Sending template to reporting
service.......................................

2019-10-29 11:06:16,590 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Virtual pagination added for one page reports.

2019-10-29 11:06:16,590 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  Report Preparing ...

2019-10-29 11:06:17,062 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  Prepare [OK]

2019-10-29 11:06:17,062 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  Report filling ...

2019-10-29 11:06:17,102 DEBUG [io.undertow.request] (default I/O-8) Matched default
handler path /messagebroker/amfspsecurepolling

2019-10-29 11:06:17,102 DEBUG [io.undertow.request.security] (default task-52) Security
constraints for request /messagebroker/amfspsecurepolling are
[SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[$SYSTEM_ROLE_LOGIN$]}]

2019-10-29 11:06:17,102 DEBUG [io.undertow.request.security] (default task-52)
Authenticating required for request HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:17,103 DEBUG [io.undertow.request.security] (default task-52) Setting
authentication required for exchange HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:17,103 DEBUG [io.undertow.request.security] (default task-52) Attempting
to authenticate HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}},
authentication required: true

2019-10-29 11:06:17,140 DEBUG [io.undertow.request.security] (default task-52)
Authenticated as dummy.user_login_postfix_SXl2qtNGf3OfyluZzQ90aIH9MV1ZXq1baqSb8mUD, roles
[Super Users, $SYSTEM_ROLE_LOGIN$, Super User (RSA 2nd Level Authentification),]

2019-10-29 11:06:17,141 DEBUG [io.undertow.request.security] (default task-52)
Authentication outcome was AUTHENTICATED with method
io.undertow.security.impl.CachedAuthenticatedSessionMechanism@3a039cf for
HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:06:17,141 DEBUG [io.undertow.request.security] (default task-52)
Authentication result was AUTHENTICATED for HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:06:22,428 DEBUG [io.undertow.request] (default I/O-8) Matched default
handler path /messagebroker/amfspsecurepolling

2019-10-29 11:06:22,428 DEBUG [io.undertow.request.security] (default task-47) Security
constraints for request /messagebroker/amfspsecurepolling are
[SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[$SYSTEM_ROLE_LOGIN$]}]

2019-10-29 11:06:22,429 DEBUG [io.undertow.request.security] (default task-47)
Authenticating required for request HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:22,429 DEBUG [io.undertow.request.security] (default task-47) Setting
authentication required for exchange HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:06:22,429 DEBUG [io.undertow.request.security] (default task-47) Attempting
to authenticate HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}},
authentication required: true

2019-10-29 11:06:46,707 DEBUG [io.undertow.request] (default I/O-6) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37754

2019-10-29 11:06:52,001 DEBUG [io.undertow.request] (default I/O-2) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37734

2019-10-29 11:07:06,591 DEBUG [io.undertow.request] (default I/O-4) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37780

2019-10-29 11:07:11,857 DEBUG [io.undertow.request] (default I/O-2) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37782

2019-10-29 11:07:12,522 DEBUG [io.undertow.request] (default I/O-6) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37770

2019-10-29 11:07:17,239 DEBUG [io.undertow.request] (default I/O-8) UT005035: Closing
channel because of parse timeout for remote address /192.168.0.1:37744

2019-10-29 11:08:20,923 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  fill [OK]

2019-10-29 11:08:20,923 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  Genrating TOC

2019-10-29 11:08:20,937 ERROR [org.apache.pdfbox.filter.FlateFilter] (default task-51)
FlateFilter: stop reading corrupt stream due to a DataFormatException

2019-10-29 11:08:22,298 ERROR [org.apache.pdfbox.filter.FlateFilter] (default task-51)
FlateFilter: stop reading corrupt stream due to a DataFormatException

2019-10-29 11:08:23,303 DEBUG [io.undertow.request.security] (default task-47)
Authentication outcome was NOT_ATTEMPTED with method
io.undertow.security.impl.CachedAuthenticatedSessionMechanism@3a039cf for
HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:08:23,304 DEBUG [io.undertow.request.security] (default task-47)
Authentication outcome was NOT_AUTHENTICATED with method
com.os.sp.undertow.extension.OFSFormAuthenticationMechanism@389cee8 for
HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[Sat, 25 Dec 1999 00:00:00 GMT],
Cache-Control=[no-cache, no-store, must-revalidate, no-cache], X-Powered-By=[Undertow/1],
Server=[WildFly/10], Pragma=[no-cache], Content-Type=[application/x-amf],
Content-Length=[665], Date=[Tue, 29 Oct 2019 11:08:23 GMT]}}

2019-10-29 11:08:23,304 DEBUG [io.undertow.request.security] (default task-47)
Authentication result was AUTHENTICATED for HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[Sat, 25 Dec 1999 00:00:00 GMT],
Cache-Control=[no-cache, no-store, must-revalidate, no-cache], X-Powered-By=[Undertow/1],
Server=[WildFly/10], Pragma=[no-cache], Content-Type=[application/x-amf],
Content-Length=[665], Date=[Tue, 29 Oct 2019 11:08:23 GMT]}}

2019-10-29 11:08:23,371 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  TOC [OK]

2019-10-29 11:08:23,372 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  Report exporting ...

2019-10-29 11:08:23,393 ERROR [org.apache.pdfbox.filter.FlateFilter] (default task-51)
FlateFilter: stop reading corrupt stream due to a DataFormatException

2019-10-29 11:08:24,766 ERROR [org.apache.pdfbox.filter.FlateFilter] (default task-51)
FlateFilter: stop reading corrupt stream due to a DataFormatException

2019-10-29 11:08:25,907 WARN  [com.os.sp.reporting.service.jasper.JasperReportingEngine]
(default task-51) Procedures Report_____________  export [OK]

2019-10-29 11:08:25,951 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Comparing File Size : [13861381 > 1048576000] false

2019-10-29 11:08:25,957 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: Passed file size test

2019-10-29 11:08:26,555 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: Report successfully merged

2019-10-29 11:08:26,555 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: Filling required response parameters

2019-10-29 11:08:26,555 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: finding current filter

2019-10-29 11:08:26,597 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: filter found: true

2019-10-29 11:08:26,623 WARN 
[com.os.sp.enterprise.reportingengine.ejb.StatelessReportingFacadeBean] (default task-51)
Reporting: Everything looks okay, before sending response to the client (Flex).

2019-10-29 11:08:29,062 DEBUG [io.undertow.request] (default I/O-4) Matched default
handler path /messagebroker/amfspsecurepolling

2019-10-29 11:08:29,063 DEBUG [io.undertow.request.security] (default task-53) Security
constraints for request /messagebroker/amfspsecurepolling are
[SingleConstraintMatch{emptyRoleSemantic=PERMIT, requiredRoles=[$SYSTEM_ROLE_LOGIN$]}]

2019-10-29 11:08:29,063 DEBUG [io.undertow.request.security] (default task-53)
Authenticating required for request HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:08:29,063 DEBUG [io.undertow.request.security] (default task-53) Setting
authentication required for exchange HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {X-Powered-By=[Undertow/1], Server=[WildFly/10]}}

2019-10-29 11:08:29,063 DEBUG [io.undertow.request.security] (default task-53) Attempting
to authenticate HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}},
authentication required: true

2019-10-29 11:08:29,063 DEBUG [io.undertow.request.security] (default task-53)
Authentication outcome was NOT_ATTEMPTED with method
io.undertow.security.impl.CachedAuthenticatedSessionMechanism@3a039cf for
HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[0], Cache-Control=[no-cache, no-store,
must-revalidate], X-Powered-By=[Undertow/1], Server=[WildFly/10], Pragma=[no-cache]}}

2019-10-29 11:08:29,065 DEBUG [io.undertow.request.security] (default task-53)
Authentication outcome was NOT_AUTHENTICATED with method
com.os.sp.undertow.extension.OFSFormAuthenticationMechanism@389cee8 for
HttpServerExchange{ POST /messagebroker/amfspsecurepolling request
{X-Requested-With=[ShockwaveFlash/32.0.0.270], Accept=[*/*],
Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[Sat, 25 Dec 1999 00:00:00 GMT],
Cache-Control=[no-cache, no-store, must-revalidate, no-cache], X-Powered-By=[Undertow/1],
Server=[WildFly/10], Pragma=[no-cache], Content-Type=[application/x-amf],
Content-Length=[664], Date=[Tue, 29 Oct 2019 11:08:29 GMT]}}

2019-10-29 11:08:29,065 DEBUG [io.undertow.request.security] (default task-53)
Authentication result was AUTHENTICATED for HttpServerExchange{ POST
/messagebroker/amfspsecurepolling request {X-Requested-With=[ShockwaveFlash/32.0.0.270],
Accept=[*/*], Accept-Language=[en-GB,en-US;q=0.9,en;q=0.8], Sec-Fetch-Mode=[no-cors],
Accept-Encoding=[gzip, deflate, br], Origin=[https://qas09.qa.dummyurl.com],
User-Agent=[Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/77.0.3865.120 Safari/537.36], Connection=[keep-alive],
Sec-Fetch-Site=[same-origin], Content-Length=[111], Content-Type=[application/x-amf],
Cookie=[JSESSIONID=V4tuZXIJSYp4H7wmHqjbgHmmGcrZSpv1UwqW0qi0],
Referer=[https://qas09.qa.dummyurl.com/wwwsecure/flexApp?cachebreak=20191...,
Host=[qas09.qa.dummyurl.com]} response {Expires=[Sat, 25 Dec 1999 00:00:00 GMT],
Cache-Control=[no-cache, no-store, must-revalidate, no-cache], X-Powered-By=[Undertow/1],
Server=[WildFly/10], Pragma=[no-cache], Content-Type=[application/x-amf],
Content-Length=[664], Date=[Tue, 29 Oct 2019 11:08:29 GMT]}}

Any help in this regard with be highly appreciated.

--
This message was sent by Atlassian Jira
(v7.13.8#713008)

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[jboss-jira] [JBoss JIRA] (WFLY-12736) Intermittent Session Termination in Wildfly 10