Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
Mar 28, 2018 11:30:30 AM org.jboss.arquillian.container.tomcat.managed.TomcatManagedContainer start
INFO: Starting Tomcat with: [/usr/lib/jvm/java-1.8.0-openjdk-1.8.0.161-0.b14.el7_4.x86_64/jre/bin/java, -Djava.util.logging.config.file=/home/jenkins/narayana/apache-tomcat-9.0.4/conf/logging.properties, -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager, -Dcom.sun.management.jmxremote.port=8089, -Dcom.sun.management.jmxremote.ssl=false, -Dcom.sun.management.jmxremote.authenticate=false, -Dorg.jboss.byteman.verbose, -Djboss.modules.system.pkgs=org.jboss.byteman, -Dorg.jboss.byteman.transform.all, -javaagent:/home/jenkins/narayana/tomcat/tomcat-jta/target/lib/byteman.jar=script:/home/jenkins/narayana/tomcat/tomcat-jta/target/test-classes/scripts.btm,listener:true, -classpath, /home/jenkins/narayana/apache-tomcat-9.0.4/bin/bootstrap.jar:/home/jenkins/narayana/apache-tomcat-9.0.4/bin/tomcat-juli.jar, -Djava.endorsed.dirs=/home/jenkins/narayana/apache-tomcat-9.0.4/endorsed, -Dcatalina.base=/home/jenkins/narayana/apache-tomcat-9.0.4, -Dcatalina.home=/home/jenkins/narayana/apache-tomcat-9.0.4, -Djava.io.tmpdir=/home/jenkins/narayana/apache-tomcat-9.0.4/temp, org.apache.catalina.startup.Bootstrap, -config, /home/jenkins/narayana/apache-tomcat-9.0.4/conf/server.xml, start]
TransformListener() : accepting requests on localhost:9091
28-Mar-2018 11:30:31.538 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version: Apache Tomcat/9.0.4
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Jan 18 2018 19:42:17 UTC
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server number: 9.0.4.0
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 3.10.0-693.17.1.el7.x86_64
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/lib/jvm/java-1.8.0-openjdk-1.8.0.161-0.b14.el7_4.x86_64/jre
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_161-b14
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation
28-Mar-2018 11:30:31.540 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /home/jenkins/narayana/apache-tomcat-9.0.4
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /home/jenkins/narayana/apache-tomcat-9.0.4
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/home/jenkins/narayana/apache-tomcat-9.0.4/conf/logging.properties
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.port=8089
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.ssl=false
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.authenticate=false
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.jboss.byteman.verbose
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djboss.modules.system.pkgs=org.jboss.byteman
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.jboss.byteman.transform.all
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -javaagent:/home/jenkins/narayana/tomcat/tomcat-jta/target/lib/byteman.jar=script:/home/jenkins/narayana/tomcat/tomcat-jta/target/test-classes/scripts.btm,listener:true
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.endorsed.dirs=/home/jenkins/narayana/apache-tomcat-9.0.4/endorsed
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/home/jenkins/narayana/apache-tomcat-9.0.4
28-Mar-2018 11:30:31.541 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/home/jenkins/narayana/apache-tomcat-9.0.4
28-Mar-2018 11:30:31.542 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/home/jenkins/narayana/apache-tomcat-9.0.4/temp
28-Mar-2018 11:30:31.542 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: [/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib]
28-Mar-2018 11:30:31.597 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"]
28-Mar-2018 11:30:31.604 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
28-Mar-2018 11:30:31.609 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-8009"]
28-Mar-2018 11:30:31.610 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
28-Mar-2018 11:30:31.610 INFO [main] org.apache.catalina.startup.Catalina.load Initialization processed in 319 ms
28-Mar-2018 11:30:31.629 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
28-Mar-2018 11:30:31.629 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/9.0.4
28-Mar-2018 11:30:31.634 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/ROOT]
28-Mar-2018 11:30:31.889 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:31.925 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/ROOT] has finished in [290] ms
28-Mar-2018 11:30:31.925 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/docs]
28-Mar-2018 11:30:31.978 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:31.980 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/docs] has finished in [55] ms
28-Mar-2018 11:30:31.980 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/examples]
28-Mar-2018 11:30:32.121 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:32.145 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/examples] has finished in [164] ms
28-Mar-2018 11:30:32.145 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/host-manager]
28-Mar-2018 11:30:32.205 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:32.208 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/host-manager] has finished in [63] ms
28-Mar-2018 11:30:32.208 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/manager]
28-Mar-2018 11:30:32.258 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:32.259 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/manager] has finished in [51] ms
28-Mar-2018 11:30:32.261 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
28-Mar-2018 11:30:32.266 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"]
28-Mar-2018 11:30:32.271 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 661 ms
Mar 28, 2018 11:30:32 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/tomcat/tomcat-jta/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.tomcat:tomcat-jta:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:32 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/common/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:common:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:33 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/common/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:common:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:33 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/common/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:common:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:34 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/common/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:common:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:34 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/narayana-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:narayana-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:34 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/narayana-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:narayana-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:34 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/narayana-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:narayana-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/narayana-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana:narayana-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjuna/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjuna:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjuna/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjuna:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjuna/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjuna:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjuna/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjuna:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:36 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjunacore-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjunacore-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:37 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjunacore-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjunacore-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:37 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjunacore-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjunacore-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:37 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/arjunacore/arjunacore-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.arjunacore:arjunacore-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:37 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jta/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jta:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jta/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jta:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jta/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jta:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jta/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jta:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/narayana-jta-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:narayana-jta-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/narayana-jta-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:narayana-jta-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:38 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/narayana-jta-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:narayana-jta-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:40 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/narayana-jta-all/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:narayana-jta-all:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:40 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jdbc/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jdbc:5.8.1.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
Mar 28, 2018 11:30:40 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jdbc/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jdbc:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jbossThirdParty (https://repository.jboss.org/nexus/content/repositories/thirdparty-releases/)
Mar 28, 2018 11:30:40 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jdbc/5.8.1.Final-SNAPSHOT/maven-metadata.xml from https://repository.jboss.org/nexus/content/groups/public/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jdbc:5.8.1.Final-SNAPSHOT/maven-metadata.xml in jboss-public-repository-group (https://repository.jboss.org/nexus/content/groups/public/)
Mar 28, 2018 11:30:41 AM org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
WARNING: Failed downloading org/jboss/narayana/jta/jdbc/5.8.1.Final-SNAPSHOT/maven-metadata.xml from http://download.java.net/maven/2/. Reason:
org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.narayana.jta:jdbc:5.8.1.Final-SNAPSHOT/maven-metadata.xml in download-java-net (http://download.java.net/maven/2/)
Mar 28, 2018 11:30:41 AM org.jboss.narayana.tomcat.jta.integration.BaseITCase getDeployment
INFO: test.war:
/WEB-INF/
/WEB-INF/lib/
/WEB-INF/lib/httpcore-4.4.1.jar
/WEB-INF/lib/jboss-annotations-api_1.2_spec-1.0.0.Final.jar
/WEB-INF/lib/jboss-connector-api_1.7_spec-1.0.0.Final.jar
/WEB-INF/lib/arjuna-5.8.1.Final-SNAPSHOT.jar
/WEB-INF/lib/activation-1.1.1.jar
/WEB-INF/lib/async-http-servlet-3.0-3.1.0.Beta1.jar
/WEB-INF/lib/jboss-jaxrs-api_2.0_spec-1.0.0.Final.jar
/WEB-INF/lib/jboss-logging-3.2.1.Final.jar
/WEB-INF/lib/resteasy-servlet-initializer-3.1.0.Beta1.jar
/WEB-INF/lib/commons-io-2.4.jar
/WEB-INF/lib/tomcat-jta-5036220611303874203.jar
/WEB-INF/lib/commons-logging-1.2.jar
/WEB-INF/lib/common-5.8.1.Final-SNAPSHOT.jar
/WEB-INF/lib/jboss-transaction-api_1.2_spec-1.0.0.Final.jar
/WEB-INF/lib/jta-5.8.1.Final-SNAPSHOT.jar
/WEB-INF/lib/jconsole.jar
/WEB-INF/lib/resteasy-jaxrs-3.1.0.Beta1.jar
/WEB-INF/lib/jdbc-5.8.1.Final-SNAPSHOT.jar
/WEB-INF/lib/jcip-annotations-1.0.jar
/WEB-INF/lib/httpclient-4.5.jar
/WEB-INF/lib/commons-codec-1.9.jar
/WEB-INF/lib/jboss-transaction-spi-7.6.0.Final.jar
/WEB-INF/classes/
/WEB-INF/classes/jbossts-properties.xml
/WEB-INF/classes/org/
/WEB-INF/classes/org/jboss/
/WEB-INF/classes/org/jboss/narayana/
/WEB-INF/classes/org/jboss/narayana/tomcat/
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/integration/
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/integration/app/
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/integration/app/TestExecutor.class
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/integration/app/TestXAResource.class
/WEB-INF/classes/org/jboss/narayana/tomcat/jta/integration/app/TestApplication.class
/WEB-INF/web.xml
/WEB-INF/beans.xml
Mar 28, 2018 11:30:42 AM org.jboss.narayana.tomcat.jta.integration.AbstractCase init
INFO: Allocating a new database might take many minutes, depending on the mode the test suite operates in.
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
log4j:WARN No appenders could be found for logger (io.netty.util.internal.logging.InternalLoggerFactory).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Mar 28, 2018 11:30:42 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onStart
INFO: Pulling image postgres:10
Mar 28, 2018 11:30:42 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onNext
INFO: Next... null
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onNext
INFO: Next... 10
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onNext
INFO: Next... null
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onNext
INFO: Next... null
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onComplete
INFO: Constructing container.
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onComplete
INFO: Starting container.
Mar 28, 2018 11:30:43 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator$1 onComplete
INFO: The database container has successfully opened TCP socket 127.0.0.1:5432
28-Mar-2018 11:30:56.594 INFO [http-nio-8080-exec-3] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/test.war]
28-Mar-2018 11:30:57.051 INFO [http-nio-8080-exec-3] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:30:57.172 WARNING [http-nio-8080-exec-3] org.jboss.narayana.tomcat.jta.NarayanaJtaServletContextListener.initNodeIdentifier Node identifier was not set. Setting it to the default value: 1
org.jboss.byteman.agent.Transformer : possible trigger for rule Simulate system crash in class com.arjuna.ats.arjuna.coordinator.BasicAction
RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.phase2Commit(boolean) void for rule Simulate system crash
org.jboss.byteman.agent.Transformer : inserted trigger for Simulate system crash in class com.arjuna.ats.arjuna.coordinator.BasicAction
28-Mar-2018 11:30:57.359 INFO [http-nio-8080-exec-3] com.arjuna.ats.arjuna.recovery.TransactionStatusManager.start ARJUNA012170: TransactionStatusManager started on port 33505 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
28-Mar-2018 11:30:57.435 INFO [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyDeployment.processApplication RESTEASY002225: Deploying javax.ws.rs.core.Application: class org.jboss.narayana.tomcat.jta.integration.app.TestApplication
28-Mar-2018 11:30:57.435 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.IIOImageProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.435 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.ReaderProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.436 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.AcceptEncodingGZIPFilter is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.436 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.GZIPEncodingInterceptor is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.436 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.JaxrsFormProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.436 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.SourceProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.GZIPDecodingInterceptor is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultNumberWriter is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultTextPlain is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FileProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FormUrlEncodedProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.StringTextStar is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.InputStreamProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.437 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DocumentProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:30:57.438 WARN [http-nio-8080-exec-3] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DataSourceProvider is already registered. 2nd registration is being ignored.
org.jboss.byteman.agent.Transformer : possible trigger for rule Trigger system crash simulation in class org.jboss.narayana.tomcat.jta.integration.app.TestXAResource
RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.narayana.tomcat.jta.integration.app.TestXAResource.start(javax.transaction.xa.Xid,int) void for rule Trigger system crash simulation
org.jboss.byteman.agent.Transformer : inserted trigger for Trigger system crash simulation in class org.jboss.narayana.tomcat.jta.integration.app.TestXAResource
28-Mar-2018 11:30:57.462 INFO [http-nio-8080-exec-3] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/test.war] has finished in [868] ms
Mar 28, 2018 11:30:57 AM org.jboss.arquillian.container.tomcat.ProtocolMetadataParser connect
INFO: Connecting to JMX at service:jmx:rmi:///jndi/rmi://localhost:8089/jmxrmi
28-Mar-2018 11:30:57.657 INFO [http-nio-8080-exec-4] org.jboss.narayana.tomcat.jta.integration.app.TestExecutor.verifyJndi Verifying JNDI
28-Mar-2018 11:30:57.691 FINE [http-nio-8080-exec-4] org.postgresql.Driver.loadDefaultProperties Loading driver configuration via classloader ParallelWebappClassLoader
context: test
delegate: false
----------> Parent Classloader:
java.net.URLClassLoader@299a06ac
28-Mar-2018 11:30:57.692 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.699 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.699 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.699 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.704 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.710 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.710 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.745 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.761 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.763 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.768 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.768 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.769 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.769 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.769 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.769 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.769 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.770 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.770 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.774 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.775 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.775 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.776 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.781 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.782 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.783 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.787 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.788 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.789 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.793 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.793 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.794 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.794 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.794 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.794 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.794 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.795 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.795 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.798 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.801 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.801 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.801 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.801 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.801 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.802 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.802 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.802 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.806 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.807 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.808 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.812 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.813 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.813 FINE [http-nio-8080-exec-4] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.813 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:30:57.813 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:30:57.814 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:30:57.814 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:30:57.814 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:30:57.814 FINE [http-nio-8080-exec-4] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:30:57.818 FINE [http-nio-8080-exec-4] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:30:57.819 FINE [http-nio-8080-exec-4] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:30:57.850 WARNING [http-nio-8080-exec-6] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [test] registered the JDBC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
28-Mar-2018 11:30:58.356 INFO [http-nio-8080-exec-6] org.apache.catalina.startup.HostConfig.undeploy Undeploying context [/test]
Mar 28, 2018 11:30:58 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator cleanDB
INFO: Listing containers for cleanup.
Mar 28, 2018 11:30:58 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$cleanDB$5
INFO: Restarting running container.
Mar 28, 2018 11:31:10 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$cleanDB$5
INFO: The database container has successfully restarted. TCP socket 127.0.0.1:5432 opened.
28-Mar-2018 11:31:12.486 INFO [http-nio-8080-exec-5] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/test.war]
28-Mar-2018 11:31:12.926 INFO [http-nio-8080-exec-5] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
28-Mar-2018 11:31:12.945 WARNING [http-nio-8080-exec-5] org.jboss.narayana.tomcat.jta.NarayanaJtaServletContextListener.initNodeIdentifier Node identifier was not set. Setting it to the default value: 1
org.jboss.byteman.agent.Transformer : possible trigger for rule Simulate system crash in class com.arjuna.ats.arjuna.coordinator.BasicAction
RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.phase2Commit(boolean) void for rule Simulate system crash
org.jboss.byteman.agent.Transformer : inserted trigger for Simulate system crash in class com.arjuna.ats.arjuna.coordinator.BasicAction
28-Mar-2018 11:31:13.362 INFO [http-nio-8080-exec-5] com.arjuna.ats.arjuna.recovery.TransactionStatusManager.start ARJUNA012170: TransactionStatusManager started on port 40729 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
28-Mar-2018 11:31:13.429 INFO [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyDeployment.processApplication RESTEASY002225: Deploying javax.ws.rs.core.Application: class org.jboss.narayana.tomcat.jta.integration.app.TestApplication
28-Mar-2018 11:31:13.429 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultNumberWriter is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.429 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.GZIPEncodingInterceptor is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.430 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.AcceptEncodingGZIPFilter is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.430 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FileProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.430 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.JaxrsFormProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.430 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.ReaderProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.IIOImageProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DocumentProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.StringTextStar is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FormUrlEncodedProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.GZIPDecodingInterceptor is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.InputStreamProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.SourceProvider is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultTextPlain is already registered. 2nd registration is being ignored.
28-Mar-2018 11:31:13.431 WARN [http-nio-8080-exec-5] org.jboss.resteasy.spi.ResteasyProviderFactory.registerProvider RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DataSourceProvider is already registered. 2nd registration is being ignored.
org.jboss.byteman.agent.Transformer : possible trigger for rule Trigger system crash simulation in class org.jboss.narayana.tomcat.jta.integration.app.TestXAResource
RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.narayana.tomcat.jta.integration.app.TestXAResource.start(javax.transaction.xa.Xid,int) void for rule Trigger system crash simulation
org.jboss.byteman.agent.Transformer : inserted trigger for Trigger system crash simulation in class org.jboss.narayana.tomcat.jta.integration.app.TestXAResource
28-Mar-2018 11:31:13.453 INFO [http-nio-8080-exec-5] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/home/jenkins/narayana/apache-tomcat-9.0.4/webapps/test.war] has finished in [967] ms
Mar 28, 2018 11:31:13 AM org.jboss.arquillian.container.tomcat.ProtocolMetadataParser connect
INFO: Connecting to JMX at service:jmx:rmi:///jndi/rmi://localhost:8089/jmxrmi
28-Mar-2018 11:31:13.478 INFO [http-nio-8080-exec-7] org.jboss.narayana.tomcat.jta.integration.app.TestExecutor.verifyRecovery Verifying recovery
28-Mar-2018 11:31:13.494 FINE [http-nio-8080-exec-7] org.postgresql.Driver.loadDefaultProperties Loading driver configuration via classloader ParallelWebappClassLoader
context: test
delegate: false
----------> Parent Classloader:
java.net.URLClassLoader@299a06ac
28-Mar-2018 11:31:13.494 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.498 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.499 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.499 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.504 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.506 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.507 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.540 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.555 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.555 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.555 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.555 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.555 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.556 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.556 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.556 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.561 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.562 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.563 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.567 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.568 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.568 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.568 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.568 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.569 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.569 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.569 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.569 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.572 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.573 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.573 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.574 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.581 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.582 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.582 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.582 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.582 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.583 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.583 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.583 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.583 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.587 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.587 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.588 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.589 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.592 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.592 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.593 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.597 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.597 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.598 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.601 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:13.602 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:13.603 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:13.603 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:13.606 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:13.606 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setTransactionIsolation setTransactionIsolation = SERIALIZABLE
28-Mar-2018 11:31:13.613 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setAutoCommit setAutoCommit = false
Rule.execute called for Trigger system crash simulation_3
HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper
calling activated() for helper class org.jboss.byteman.rule.helper.Helper
Default helper activated
calling installed(Trigger system crash simulation) for helper classorg.jboss.byteman.rule.helper.Helper
Installed rule using default helper : Trigger system crash simulation
Trigger system crash simulation execute
rule.debug{Trigger system crash simulation} : Setting commit failure flag
28-Mar-2018 11:31:13.639 INFO [http-nio-8080-exec-7] com.arjuna.ats.internal.jdbc.ConnectionImple.registerDatabase ARJUNA017008: No modifier information found for db. Connection will be closed immediately Pooled connection wrapping physical connection org.postgresql.jdbc.PgConnection@7ac41aa9
Rule.execute called for Simulate system crash_2
HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper
calling installed(Simulate system crash) for helper classorg.jboss.byteman.rule.helper.Helper
Installed rule using default helper : Simulate system crash
Simulate system crash execute
rule.debug{Simulate system crash} : Simulating system crash
caught ThrowException
28-Mar-2018 11:31:17.021 FINE [http-nio-8080-exec-7] org.postgresql.Driver.connect Connecting with URL: jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
28-Mar-2018 11:31:17.021 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.<init> PostgreSQL JDBC Driver 42.2.1
28-Mar-2018 11:31:17.022 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setDefaultFetchSize setDefaultFetchSize = 0
28-Mar-2018 11:31:17.022 FINE [http-nio-8080-exec-7] org.postgresql.jdbc.PgConnection.setPrepareThreshold setPrepareThreshold = 5
28-Mar-2018 11:31:17.022 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Trying to establish a protocol version 3 connection to 127.0.0.1:5432
28-Mar-2018 11:31:17.022 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Receive Buffer Size is 530,648
28-Mar-2018 11:31:17.022 FINE [http-nio-8080-exec-7] org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl Send Buffer Size is 1,313,280
28-Mar-2018 11:31:17.026 FINE [http-nio-8080-exec-7] org.postgresql.ds.common.BaseDataSource.getConnection Created a XA-enabled DataSource from PostgreSQL JDBC Driver 42.2.1 for narayana_user at jdbc:postgresql://127.0.0.1:5432/narayana_db?user=narayana_user&password=narayana_pass&loginTimeout=0
Rule.execute called for Simulate system crash_2
Simulate system crash execute
28-Mar-2018 11:31:17.030 INFO [http-nio-8080-exec-7] org.jboss.narayana.tomcat.jta.integration.app.TestExecutor.didRecoveryHappen Verifying TestXAResource methods. Expected=[start, end, prepare, commit], actual=[start, end, prepare, commit]
28-Mar-2018 11:31:17.038 INFO [http-nio-8080-exec-7] org.jboss.narayana.tomcat.jta.integration.app.TestExecutor.didRecoveryHappen Verifying if database entry exists:true
28-Mar-2018 11:31:17.673 INFO [Tomcat JDBC Pool Cleaner[697960108:1522236657672]] org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading Illegal access: this web application instance has been stopped already. Could not load [com.arjuna.ats.arjuna.coordinator.SynchronizationRecord]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
java.lang.IllegalStateException: Illegal access: this web application instance has been stopped already. Could not load [com.arjuna.ats.arjuna.coordinator.SynchronizationRecord]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading(WebappClassLoaderBase.java:1295)
at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1283)
at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1142)
at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1104)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionManagerImple.getTransaction(TransactionManagerImple.java:52)
at com.arjuna.ats.internal.jdbc.ConnectionImple.checkTransaction(ConnectionImple.java:1027)
at com.arjuna.ats.internal.jdbc.ConnectionImple.createStatement(ConnectionImple.java:153)
at org.apache.tomcat.jdbc.pool.PooledConnection.validate(PooledConnection.java:525)
at org.apache.tomcat.jdbc.pool.PooledConnection.validate(PooledConnection.java:443)
at org.apache.tomcat.jdbc.pool.ConnectionPool.testAllIdle(ConnectionPool.java:1082)
at org.apache.tomcat.jdbc.pool.ConnectionPool$PoolCleaner.run(ConnectionPool.java:1471)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
Exception in thread "Tomcat JDBC Pool Cleaner[697960108:1522236657672]" java.lang.NoClassDefFoundError: com/arjuna/ats/arjuna/coordinator/SynchronizationRecord
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionManagerImple.getTransaction(TransactionManagerImple.java:52)
at com.arjuna.ats.internal.jdbc.ConnectionImple.checkTransaction(ConnectionImple.java:1027)
at com.arjuna.ats.internal.jdbc.ConnectionImple.createStatement(ConnectionImple.java:153)
at org.apache.tomcat.jdbc.pool.PooledConnection.validate(PooledConnection.java:525)
at org.apache.tomcat.jdbc.pool.PooledConnection.validate(PooledConnection.java:443)
at org.apache.tomcat.jdbc.pool.ConnectionPool.testAllIdle(ConnectionPool.java:1082)
at org.apache.tomcat.jdbc.pool.ConnectionPool$PoolCleaner.run(ConnectionPool.java:1471)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
Caused by: java.lang.ClassNotFoundException: Illegal access: this web application instance has been stopped already. Could not load [com.arjuna.ats.arjuna.coordinator.SynchronizationRecord]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1285)
at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1142)
at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1104)
... 9 more
Caused by: java.lang.IllegalStateException: Illegal access: this web application instance has been stopped already. Could not load [com.arjuna.ats.arjuna.coordinator.SynchronizationRecord]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading(WebappClassLoaderBase.java:1295)
at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1283)
... 11 more
28-Mar-2018 11:32:13.632 WARN [Transaction Reaper] com.arjuna.ats.arjuna.coordinator.TransactionReaper.check ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff7f000001:8b93:5abb7d01:8 in state RUN
28-Mar-2018 11:32:13.635 WARNING [http-nio-8080-exec-2] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [test] registered the JDBC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
28-Mar-2018 11:32:14.141 INFO [http-nio-8080-exec-2] org.apache.catalina.startup.HostConfig.undeploy Undeploying context [/test]
Mar 28, 2018 11:32:14 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator cleanDB
INFO: Listing containers for cleanup.
Mar 28, 2018 11:32:14 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$cleanDB$5
INFO: Restarting running container.
Mar 28, 2018 11:32:25 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$cleanDB$5
INFO: The database container has successfully restarted. TCP socket 127.0.0.1:5432 opened.
Mar 28, 2018 11:32:25 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator deallocateDB
INFO: Listing containers for deallocation.
Mar 28, 2018 11:32:25 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$deallocateDB$3
INFO: db.log0100600000174700017470000002254613256676476010432 0ustar00000000000000002018-03-28 11:30:48.113 UTC transaction_id:0 LOG: database system was shut down at 2018-03-28 11:30:47 UTC
2018-03-28 11:30:48.148 UTC transaction_id:0 LOG: database system is ready to accept connections
2018-03-28 11:30:57.739 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.739 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.761 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.767 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.767 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.768 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.773 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.773 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.774 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.780 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.780 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.782 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.787 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.787 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.788 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.792 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.792 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.793 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.797 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.798 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.799 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.805 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.805 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.807 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.811 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.812 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.813 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:57.818 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:30:57.818 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:30:57.819 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:30:58.425 UTC transaction_id:0 LOG: received smart shutdown request
2018-03-28 11:30:59.073 UTC transaction_id:0 LOG: worker process: logical replication launcher (PID 73) exited with exit code 1
2018-03-28 11:31:11.079 UTC transaction_id:0 LOG: database system was interrupted; last known up at 2018-03-28 11:30:48 UTC
2018-03-28 11:31:11.081 UTC transaction_id:0 LOG: incomplete startup packet
2018-03-28 11:31:11.304 UTC transaction_id:0 LOG: database system was not properly shut down; automatic recovery in progress
2018-03-28 11:31:11.327 UTC transaction_id:0 LOG: redo starts at 0/16343F0
2018-03-28 11:31:11.337 UTC transaction_id:0 LOG: invalid record length at 0/1634428: wanted 24, got 0
2018-03-28 11:31:11.337 UTC transaction_id:0 LOG: redo done at 0/16343F0
2018-03-28 11:31:11.407 UTC transaction_id:0 LOG: database system is ready to accept connections
2018-03-28 11:31:13.534 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.534 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.554 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.560 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.561 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.562 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.566 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.567 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.568 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.572 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.572 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.573 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.581 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.581 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.582 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.586 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.586 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.587 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.591 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.591 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.592 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.596 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.596 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.597 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.601 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.601 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.602 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.605 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:13.605 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:13.606 UTC transaction_id:0 LOG: execute <unnamed>: SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL SERIALIZABLE
2018-03-28 11:31:13.624 UTC transaction_id:0 LOG: execute <unnamed>: BEGIN
2018-03-28 11:31:13.625 UTC transaction_id:0 LOG: execute <unnamed>: CREATE TABLE IF NOT EXISTS test (value VARCHAR(100))
2018-03-28 11:31:13.640 UTC transaction_id:557 LOG: execute <unnamed>: INSERT INTO test VALUES ('test-entry-11:31:13.627')
2018-03-28 11:31:13.641 UTC transaction_id:557 LOG: execute <unnamed>: PREPARE TRANSACTION '131077_AAAAAAAAAAAAAP//fwAAAQAAi5Nau30BAAAACDE=_AAAAAAAAAAAAAP//fwAAAQAAi5Nau30BAAAADAAAAAAAAAAA'
2018-03-28 11:31:17.025 UTC transaction_id:0 LOG: execute <unnamed>: SET extra_float_digits = 3
2018-03-28 11:31:17.026 UTC transaction_id:0 LOG: execute <unnamed>: SET application_name = 'PostgreSQL JDBC Driver'
2018-03-28 11:31:17.027 UTC transaction_id:0 LOG: execute <unnamed>: COMMIT PREPARED '131077_AAAAAAAAAAAAAP//fwAAAQAAi5Nau30BAAAACDE=_AAAAAAAAAAAAAP//fwAAAQAAi5Nau30BAAAADAAAAAAAAAAA'
2018-03-28 11:31:17.031 UTC transaction_id:0 LOG: execute <unnamed>: BEGIN
2018-03-28 11:31:17.032 UTC transaction_id:0 LOG: execute <unnamed>: SELECT COUNT(*) FROM test WHERE value='test-entry-11:31:13.627'
2018-03-28 11:32:14.159 UTC transaction_id:0 LOG: received smart shutdown request
2018-03-28 11:32:14.186 UTC transaction_id:0 LOG: worker process: logical replication launcher (PID 31) exited with exit code 1
Mar 28, 2018 11:32:25 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$deallocateDB$3
INFO: Killing container.
Mar 28, 2018 11:32:25 AM org.jboss.narayana.tomcat.jta.integration.utils.PostgresContainerAllocator lambda$deallocateDB$3
INFO: Removing container.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.