2017-03-03 21:14:50,811 INFO [org.jboss.modules] (main) JBoss Modules version 1.5.1.Final 2017-03-03 21:14:53,147 INFO [org.jboss.msc] (main) JBoss MSC version 1.2.6.Final 2017-03-03 21:14:54,445 INFO [org.jboss.as] (MSC service thread 1-6) WFLYSRV0049: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) starting 2017-03-03 21:14:54,447 DEBUG [org.jboss.as.config] (MSC service thread 1-6) Configured system properties: awt.toolkit = sun.awt.windows.WToolkit file.encoding = Cp1251 file.encoding.pkg = sun.io file.separator = \ java.awt.graphicsenv = sun.awt.Win32GraphicsEnvironment java.awt.printerjob = sun.awt.windows.WPrinterJob java.class.path = F:\Servers\wildfly-pvsketch\jboss-modules.jar java.class.version = 52.0 java.endorsed.dirs = C:\Program Files\Java\jdk1.8.0_91\jre\lib\endorsed java.ext.dirs = C:\Program Files\Java\jdk1.8.0_91\jre\lib\ext;C:\WINDOWS\Sun\Java\lib\ext java.home = C:\Program Files\Java\jdk1.8.0_91\jre java.io.tmpdir = C:\Users\messi\AppData\Local\Temp\ java.library.path = C:\Program Files\Java\jdk1.8.0_91\bin;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Python27\;C:\Python27\Scripts;C:\ProgramData\Oracle\Java\javapath;C:\PROGRA~2\Borland\CBUILD~1\Bin;C:\PROGRA~2\Borland\CBUILD~1\Projects\Bpl;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\ATI Technologies\ATI.ACE\Core-Static;C:\Program Files (x86)\Skype\Phone\;C:\FPC\3.0.0\bin\i386-Win32;C:\Program Files\Git\cmd;C:\Program Files\TortoiseGit\bin;C:\Program Files\nodejs\;C:\Program Files\Perforce;C:\Program Files\Perforce\DVCS\;C:\WINDOWS\system32\config\systemprofile\.dnx\bin;C:\Program Files\Microsoft DNX\Dnvm\;C:\Program Files\Microsoft SQL Server\130\Tools\Binn\;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit\;C:\Program Files (x86)\Heroku\bin;C:\Program Files (x86)\git\cmd;d:\iracing;d:\iracing;C:\Program Files\CMake\bin;C:\Strawberry\c\bin;C:\Strawberry\perl\site\bin;C:\Strawberry\perl\bin;C:\Users\messi\AppData\Local\Programs\Python\Python35-32\Scripts\;C:\Users\messi\AppData\Local\Programs\Python\Python35-32\;C:\Program Files (x86)\Microsoft VS Code\bin;C:\Users\messi\AppData\Roaming\npm;%USERPROFILE%\AppData\Local\Microsoft\WindowsApps;;. java.net.preferIPv4Stack = true java.runtime.name = Java(TM) SE Runtime Environment java.runtime.version = 1.8.0_91-b14 java.specification.name = Java Platform API Specification java.specification.vendor = Oracle Corporation java.specification.version = 1.8 java.util.logging.manager = org.jboss.logmanager.LogManager java.vendor = Oracle Corporation java.vendor.url = http://java.oracle.com/ java.vendor.url.bug = http://bugreport.sun.com/bugreport/ java.version = 1.8.0_91 java.vm.info = mixed mode java.vm.name = Java HotSpot(TM) 64-Bit Server VM java.vm.specification.name = Java Virtual Machine Specification java.vm.specification.vendor = Oracle Corporation java.vm.specification.version = 1.8 java.vm.vendor = Oracle Corporation java.vm.version = 25.91-b14 javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder javax.xml.datatype.DatatypeFactory = __redirected.__DatatypeFactory javax.xml.parsers.DocumentBuilderFactory = __redirected.__DocumentBuilderFactory javax.xml.parsers.SAXParserFactory = __redirected.__SAXParserFactory javax.xml.stream.XMLEventFactory = __redirected.__XMLEventFactory javax.xml.stream.XMLInputFactory = __redirected.__XMLInputFactory javax.xml.stream.XMLOutputFactory = __redirected.__XMLOutputFactory javax.xml.transform.TransformerFactory = __redirected.__TransformerFactory javax.xml.validation.SchemaFactory:http://www.w3.org/2001/XMLSchema = __redirected.__SchemaFactory javax.xml.xpath.XPathFactory:http://java.sun.com/jaxp/xpath/dom = __redirected.__XPathFactory jboss.home.dir = F:\Servers\wildfly-pvsketch jboss.host.name = desktop-cc1pvio jboss.modules.dir = F:\Servers\wildfly-pvsketch\modules jboss.modules.system.pkgs = org.jboss.byteman jboss.node.name = desktop-cc1pvio jboss.qualified.host.name = desktop-cc1pvio jboss.server.base.dir = F:\Servers\wildfly-pvsketch\standalone jboss.server.config.dir = F:\Servers\wildfly-pvsketch\standalone\configuration jboss.server.data.dir = F:\Servers\wildfly-pvsketch\standalone\data jboss.server.deploy.dir = F:\Servers\wildfly-pvsketch\standalone\data\content jboss.server.log.dir = F:\Servers\wildfly-pvsketch\standalone\log jboss.server.name = desktop-cc1pvio jboss.server.persist.config = true jboss.server.temp.dir = F:\Servers\wildfly-pvsketch\standalone\tmp line.separator = logging.configuration = file:F:\Servers\wildfly-pvsketch\standalone\configuration/logging.properties module.path = F:\Servers\wildfly-pvsketch\modules org.jboss.boot.log.file = F:\Servers\wildfly-pvsketch\standalone\log\server.log org.jboss.resolver.warning = true org.xml.sax.driver = __redirected.__XMLReaderFactory os.arch = amd64 os.name = Windows 10 os.version = 10.0 path.separator = ; program.name = standalone.bat sun.arch.data.model = 64 sun.boot.class.path = C:\Program Files\Java\jdk1.8.0_91\jre\lib\resources.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\rt.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\sunrsasign.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\jsse.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\jce.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\charsets.jar;C:\Program Files\Java\jdk1.8.0_91\jre\lib\jfr.jar;C:\Program Files\Java\jdk1.8.0_91\jre\classes sun.boot.library.path = C:\Program Files\Java\jdk1.8.0_91\jre\bin sun.cpu.endian = little sun.cpu.isalist = amd64 sun.desktop = windows sun.io.unicode.encoding = UnicodeLittle sun.java.command = F:\Servers\wildfly-pvsketch\jboss-modules.jar -mp F:\Servers\wildfly-pvsketch\modules org.jboss.as.standalone -Djboss.home.dir=F:\Servers\wildfly-pvsketch sun.java.launcher = SUN_STANDARD sun.jnu.encoding = Cp1251 sun.management.compiler = HotSpot 64-Bit Tiered Compilers sun.os.patch.level = user.country = RU user.dir = F:\Servers\wildfly-pvsketch\bin user.home = C:\Users\messi user.language = ru user.name = messi user.script = user.timezone = Europe/Helsinki user.variant = 2017-03-03 21:14:54,450 DEBUG [org.jboss.as.config] (MSC service thread 1-6) VM Arguments: -Dprogram.name=standalone.bat -Xms64M -Xmx512M -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Dorg.jboss.boot.log.file=F:\Servers\wildfly-pvsketch\standalone\log\server.log -Dlogging.configuration=file:F:\Servers\wildfly-pvsketch\standalone\configuration/logging.properties 2017-03-03 21:15:00,035 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http) 2017-03-03 21:15:00,096 INFO [org.xnio] (MSC service thread 1-8) XNIO version 3.3.4.Final 2017-03-03 21:15:00,103 INFO [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.3.4.Final 2017-03-03 21:15:00,179 INFO [org.jboss.remoting] (MSC service thread 1-3) JBoss Remoting version 4.0.18.Final 2017-03-03 21:15:00,192 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 54) WFLYTX0013: Node identifier property is set to the default value. Please make sure it is unique. 2017-03-03 21:15:00,205 INFO [org.jboss.as.security] (ServerService Thread Pool -- 53) WFLYSEC0002: Activating Security Subsystem 2017-03-03 21:15:00,206 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 38) WFLYCLINF0001: Activating Infinispan subsystem. 2017-03-03 21:15:00,214 INFO [org.jboss.as.security] (MSC service thread 1-7) WFLYSEC0001: Current PicketBox version=4.9.4.Final 2017-03-03 21:15:00,222 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 44) WFLYJSF0007: Activated the following JSF Implementations: [main] 2017-03-03 21:15:00,232 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 37) WFLYIO001: Worker 'default' has auto-configured to 8 core threads with 64 task threads based on your 4 available processors 2017-03-03 21:15:00,233 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 56) WFLYWS0002: Activating WebServices Extension 2017-03-03 21:15:00,265 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 46) WFLYNAM0001: Activating Naming Subsystem 2017-03-03 21:15:00,277 INFO [org.jboss.as.connector] (MSC service thread 1-6) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.3.2.Final) 2017-03-03 21:15:00,391 INFO [org.jboss.as.naming] (MSC service thread 1-5) WFLYNAM0003: Starting Naming Service 2017-03-03 21:15:00,393 INFO [org.jboss.as.mail.extension] (MSC service thread 1-7) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default] 2017-03-03 21:15:00,718 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 33) WFLYJCA0005: Deploying non-JDBC-compliant driver class com.mysql.jdbc.Driver (version 5.1) 2017-03-03 21:15:00,724 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-7) WFLYJCA0018: Started Driver service with driver-name = MySqlJDBCDriver 2017-03-03 21:15:00,751 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 33) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 2017-03-03 21:15:00,752 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-1) WFLYJCA0018: Started Driver service with driver-name = h2 2017-03-03 21:15:00,834 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0003: Undertow 1.3.15.Final starting 2017-03-03 21:15:00,834 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 55) WFLYUT0003: Undertow 1.3.15.Final starting 2017-03-03 21:15:01,406 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 55) WFLYUT0014: Creating file handler for path 'F:\Servers\wildfly-pvsketch/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]'] 2017-03-03 21:15:01,848 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0012: Started server default-server. 2017-03-03 21:15:01,883 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0018: Host default-host starting 2017-03-03 21:15:01,978 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/pvcomplete] 2017-03-03 21:15:01,980 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/hardware] 2017-03-03 21:15:01,981 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-6) WFLYJCA0001: Bound data source [java:jboss/datasources/pvcomplete_amazon] 2017-03-03 21:15:01,981 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS] 2017-03-03 21:15:02,105 INFO [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0006: Undertow HTTP listener default listening on 0.0.0.0:8180 2017-03-03 21:15:02,120 INFO [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 64 (per class), which is derived from thread worker pool sizing. 2017-03-03 21:15:02,124 INFO [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 16 (per class), which is derived from the number of CPUs on this host. 2017-03-03 21:15:02,651 INFO [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0027: Starting deployment of "pvsketch.war" (runtime-name: "pvsketch.war") 2017-03-03 21:15:02,684 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-8) WFLYDS0013: Started FileSystemDeploymentService for directory F:\Servers\wildfly-pvsketch\standalone\deployments 2017-03-03 21:15:03,285 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-6) ISPN000128: Infinispan version: Infinispan 'Mahou' 8.1.0.Final 2017-03-03 21:15:03,286 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-7) ISPN000128: Infinispan version: Infinispan 'Mahou' 8.1.0.Final 2017-03-03 21:15:04,828 INFO [org.jboss.ws.common.management] (MSC service thread 1-5) JBWS022052: Starting JBossWS 5.1.3.Final (Apache CXF 3.1.4) 2017-03-03 21:15:09,968 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry xercesImpl.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:09,968 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry xml-apis.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:09,969 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry serializer.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:16,215 INFO [org.jboss.as.jpa] (MSC service thread 1-8) WFLYJPA0002: Read persistence.xml for pvsketchPrimaryPU 2017-03-03 21:15:16,216 INFO [org.jboss.as.jpa] (MSC service thread 1-8) WFLYJPA0002: Read persistence.xml for hardwareDBPU 2017-03-03 21:15:16,216 INFO [org.jboss.as.jpa] (MSC service thread 1-8) WFLYJPA0002: Read persistence.xml for pvMigrationPU 2017-03-03 21:15:17,306 WARN [org.jboss.as.dependency.private] (MSC service thread 1-7) WFLYSRV0018: Deployment "deployment.pvsketch.war" is using a private module ("org.infinispan:main") which may be changed or removed in future versions without notice. 2017-03-03 21:15:17,388 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 58) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:17,388 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 61) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:17,388 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 60) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:17,501 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 60) HHH000204: Processing PersistenceUnitInfo [ name: pvMigrationPU ...] 2017-03-03 21:15:17,501 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 58) HHH000204: Processing PersistenceUnitInfo [ name: hardwareDBPU ...] 2017-03-03 21:15:17,501 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 61) HHH000204: Processing PersistenceUnitInfo [ name: pvsketchPrimaryPU ...] 2017-03-03 21:15:17,777 INFO [org.jboss.weld.deployer] (MSC service thread 1-1) WFLYWELD0003: Processing weld deployment pvsketch.war 2017-03-03 21:15:18,428 INFO [org.hibernate.Version] (ServerService Thread Pool -- 61) HHH000412: Hibernate Core {5.0.7.Final} 2017-03-03 21:15:18,429 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 61) HHH000206: hibernate.properties not found 2017-03-03 21:15:18,430 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 61) HHH000021: Bytecode provider name : javassist 2017-03-03 21:15:18,470 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 61) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:18,470 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 60) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:18,470 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 58) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:18,550 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 61) HCANN000001: Hibernate Commons Annotations {5.0.1.Final} 2017-03-03 21:15:18,675 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-1) HV000001: Hibernate Validator 5.2.3.Final 2017-03-03 21:15:18,824 INFO [org.apache.deltaspike.core.impl.config.EnvironmentPropertyConfigSourceProvider] (MSC service thread 1-1) Custom config found by DeltaSpike. Name: 'META-INF/apache-deltaspike.properties', URL: 'vfs:/F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/classes/META-INF/apache-deltaspike.properties' 2017-03-03 21:15:18,836 INFO [org.apache.deltaspike.core.util.ProjectStageProducer] (MSC service thread 1-1) Computed the following DeltaSpike ProjectStage: Production 2017-03-03 21:15:18,843 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-1) WFLYEJB0473: JNDI bindings for session bean named 'EmailPeriodicTimer' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:app/pvsketch/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:module/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:global/pvsketch/EmailPeriodicTimer java:app/pvsketch/EmailPeriodicTimer java:module/EmailPeriodicTimer 2017-03-03 21:15:18,843 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-1) WFLYEJB0473: JNDI bindings for session bean named 'SubscriptionPeriodicTimer' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:app/pvsketch/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:module/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:global/pvsketch/SubscriptionPeriodicTimer java:app/pvsketch/SubscriptionPeriodicTimer java:module/SubscriptionPeriodicTimer 2017-03-03 21:15:18,843 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-1) WFLYEJB0473: JNDI bindings for session bean named 'DatabaseMigration' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:app/pvsketch/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:module/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:global/pvsketch/DatabaseMigration java:app/pvsketch/DatabaseMigration java:module/DatabaseMigration 2017-03-03 21:15:19,387 WARN [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-5) WFLYJCA0003: Unable to instantiate driver class "org.olap4j.driver.xmla.XmlaOlap4jDriver": java.lang.NoSuchMethodException: org.olap4j.driver.xmla.XmlaOlap4jDriver.() 2017-03-03 21:15:19,388 INFO [org.jboss.weld.deployer] (MSC service thread 1-5) WFLYWELD0006: Starting Services for CDI deployment: pvsketch.war 2017-03-03 21:15:19,479 INFO [org.jboss.weld.Version] (MSC service thread 1-5) WELD-000900: 2.3.2 (Final) 2017-03-03 21:15:19,542 INFO [org.jboss.weld.deployer] (MSC service thread 1-8) WFLYWELD0009: Starting weld service for deployment pvsketch.war 2017-03-03 21:15:19,786 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 61) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:19,786 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 58) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:19,786 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 60) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:22,213 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 61) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:22,213 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 58) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:22,272 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 61) Envers integration enabled? : true 2017-03-03 21:15:22,273 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 58) Envers integration enabled? : true 2017-03-03 21:15:22,370 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 60) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:22,379 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 60) Envers integration enabled? : true 2017-03-03 21:15:22,936 WARN [org.hibernate.boot.internal.SessionFactoryBuilderImpl] (ServerService Thread Pool -- 58) Unrecognized hbm2ddl_auto value : none. Supported values include create, create-drop, update, and validate. Ignoring 2017-03-03 21:15:23,458 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 58) HHH000397: Using ASTQueryTranslatorFactory 2017-03-03 21:15:23,633 INFO [org.jboss.weld.Event] (MSC service thread 1-3) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.message.MessageBundleExtension.detectInterfaces(@Observes ProcessAnnotatedType) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:23,637 INFO [org.jboss.weld.Event] (MSC service thread 1-3) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension.vetoBeans(@Observes ProcessAnnotatedType, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:23,667 INFO [org.jboss.weld.Event] (MSC service thread 1-3) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.interceptor.GlobalInterceptorExtension.promoteInterceptors(@Observes ProcessAnnotatedType, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:26,253 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (ServerService Thread Pool -- 61) EmailPeriodicTimer scheduled: ScheduleExpression[second=0,15,30,45 minute=* hour=* dayOfWeek=* dayOfMonth=* month=* year=* start=null end=null timezone=] 2017-03-03 21:15:26,479 INFO [com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer] (ServerService Thread Pool -- 61) EmailPeriodicTimer scheduled: ScheduleExpression[second=0 minute=0/5 hour=* dayOfWeek=* dayOfMonth=* month=* year=* start=null end=null timezone=] 2017-03-03 21:15:26,646 INFO [io.undertow.servlet] (ServerService Thread Pool -- 61) Initializing Shiro environment 2017-03-03 21:15:26,646 INFO [org.apache.shiro.web.env.EnvironmentLoader] (ServerService Thread Pool -- 61) Starting Shiro environment initialization. 2017-03-03 21:15:27,135 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 61) WFLYCLINF0002: Started jpaResetPasswordRealm.authorizationCache cache from api container 2017-03-03 21:15:27,143 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 61) WFLYCLINF0002: Started jpaRealm.authorizationCache cache from api container 2017-03-03 21:15:27,143 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 61) Init Infinispan cache manager 2017-03-03 21:15:27,144 INFO [org.apache.shiro.config.IniSecurityManagerFactory] (ServerService Thread Pool -- 61) Realms have been explicitly set on the SecurityManager instance - auto-setting of realms will not occur. 2017-03-03 21:15:27,148 INFO [org.apache.shiro.web.env.EnvironmentLoader] (ServerService Thread Pool -- 61) Shiro environment initialized in 501 ms. 2017-03-03 21:15:27,196 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 61) Initializing Mojarra 2.2.12-jbossorg-2 20150729-1131 for context '/pvsketch' 2017-03-03 21:15:29,938 WARN [org.jboss.as.weld] (ServerService Thread Pool -- 61) WFLYWELD0052: Using deployment classloader to load proxy classes for module org.jboss.resteasy.resteasy-jaxrs:main. Package-private access will not work. To fix this the module should declare dependencies on [org.jboss.weld.core, org.jboss.weld.spi] 2017-03-03 21:15:30,020 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer start... 2017-03-03 21:15:30,238 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer end... 2017-03-03 21:15:30,324 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002225: Deploying javax.ws.rs.core.Application: class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,325 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.StringMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.RackingResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ReportResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.DefaultEquipmentResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.CustomerResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.InverterResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.TestResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.AppExceptionMapper from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.PVModuleResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,326 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.TimeZoneResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,327 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AdminResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,327 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AutoPilotResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,328 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AnonymousResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,328 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.VersionResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,328 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.SubscriptionResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,328 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.StringJsonMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,329 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.UtilityProviderResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,329 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectDetailsResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,329 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectLinkResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,329 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AuthResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,329 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ManufacturerResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,330 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.UserResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,330 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.LocaleResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,330 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.BusinessResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,330 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 61) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.BooleanMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:30,462 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 61) WFLYUT0021: Registered web context: /pvsketch 2017-03-03 21:15:30,592 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0010: Deployed "pvsketch.war" (runtime-name : "pvsketch.war") 2017-03-03 21:15:30,883 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:10090/management 2017-03-03 21:15:30,883 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:10090 2017-03-03 21:15:30,883 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) started in 41763ms - Started 543 of 888 services (453 services are lazy, passive or on-demand) 2017-03-03 21:15:31,278 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 15) WFLYUT0022: Unregistered web context: /pvsketch 2017-03-03 21:15:31,285 INFO [io.undertow.servlet] (ServerService Thread Pool -- 15) Cleaning up Shiro Environment 2017-03-03 21:15:31,285 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 15) Destroy Infinispan cache manager 2017-03-03 21:15:31,285 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 15) Destroy Infinispan cache manager 2017-03-03 21:15:31,299 INFO [org.jboss.weld.deployer] (MSC service thread 1-3) WFLYWELD0010: Stopping weld service for deployment pvsketch.war 2017-03-03 21:15:31,307 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 15) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:31,309 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 22) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:31,310 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 8) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:31,314 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 15) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:31,316 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 8) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:31,316 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 15) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:31,758 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0028: Stopped deployment pvsketch.war (runtime-name: pvsketch.war) in 494ms 2017-03-03 21:15:31,827 INFO [org.jboss.as.server] (management-handler-thread - 2) WFLYSRV0009: Undeployed "pvsketch.war" (runtime-name: "pvsketch.war") 2017-03-03 21:15:31,893 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "pvsketch.war" (runtime-name: "pvsketch.war") 2017-03-03 21:15:35,830 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry xercesImpl.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:35,830 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry xml-apis.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:35,831 WARN [org.jboss.as.server.deployment] (MSC service thread 1-8) WFLYSRV0059: Class Path entry serializer.jar in /F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/lib/xalan-2.7.0.jar does not point to a valid jar for a Class-Path reference. 2017-03-03 21:15:35,869 INFO [org.jboss.as.jpa] (MSC service thread 1-7) WFLYJPA0002: Read persistence.xml for pvsketchPrimaryPU 2017-03-03 21:15:35,869 INFO [org.jboss.as.jpa] (MSC service thread 1-7) WFLYJPA0002: Read persistence.xml for hardwareDBPU 2017-03-03 21:15:35,869 INFO [org.jboss.as.jpa] (MSC service thread 1-7) WFLYJPA0002: Read persistence.xml for pvMigrationPU 2017-03-03 21:15:36,081 WARN [org.jboss.as.dependency.private] (MSC service thread 1-1) WFLYSRV0018: Deployment "deployment.pvsketch.war" is using a private module ("org.infinispan:main") which may be changed or removed in future versions without notice. 2017-03-03 21:15:36,085 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 15) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:36,086 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 15) HHH000204: Processing PersistenceUnitInfo [ name: pvsketchPrimaryPU ...] 2017-03-03 21:15:36,091 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 8) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:36,091 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 15) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:36,092 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 8) HHH000204: Processing PersistenceUnitInfo [ name: hardwareDBPU ...] 2017-03-03 21:15:36,096 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 22) WFLYJPA0010: Starting Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:36,096 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 22) HHH000204: Processing PersistenceUnitInfo [ name: pvMigrationPU ...] 2017-03-03 21:15:36,116 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 8) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:36,117 INFO [org.hibernate.orm.deprecation] (ServerService Thread Pool -- 22) HHH90000001: Found usage of deprecated setting for specifying Scanner [hibernate.ejb.resource_scanner]; use [hibernate.archive.scanner] instead 2017-03-03 21:15:36,136 INFO [org.jboss.weld.deployer] (MSC service thread 1-2) WFLYWELD0003: Processing weld deployment pvsketch.war 2017-03-03 21:15:36,172 INFO [org.apache.deltaspike.core.impl.config.EnvironmentPropertyConfigSourceProvider] (MSC service thread 1-2) Custom config found by DeltaSpike. Name: 'META-INF/apache-deltaspike.properties', URL: 'vfs:/F:/Servers/wildfly-pvsketch/bin/content/pvsketch.war/WEB-INF/classes/META-INF/apache-deltaspike.properties' 2017-03-03 21:15:36,177 INFO [org.apache.deltaspike.core.util.ProjectStageProducer] (MSC service thread 1-2) Computed the following DeltaSpike ProjectStage: Production 2017-03-03 21:15:36,182 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-2) WFLYEJB0473: JNDI bindings for session bean named 'EmailPeriodicTimer' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:app/pvsketch/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:module/EmailPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer java:global/pvsketch/EmailPeriodicTimer java:app/pvsketch/EmailPeriodicTimer java:module/EmailPeriodicTimer 2017-03-03 21:15:36,182 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-2) WFLYEJB0473: JNDI bindings for session bean named 'SubscriptionPeriodicTimer' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:app/pvsketch/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:module/SubscriptionPeriodicTimer!com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer java:global/pvsketch/SubscriptionPeriodicTimer java:app/pvsketch/SubscriptionPeriodicTimer java:module/SubscriptionPeriodicTimer 2017-03-03 21:15:36,182 INFO [org.jboss.as.ejb3.deployment] (MSC service thread 1-2) WFLYEJB0473: JNDI bindings for session bean named 'DatabaseMigration' in deployment unit 'deployment "pvsketch.war"' are as follows: java:global/pvsketch/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:app/pvsketch/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:module/DatabaseMigration!com.pvcomplete.pvsketch.api.core.util.DatabaseMigration java:global/pvsketch/DatabaseMigration java:app/pvsketch/DatabaseMigration java:module/DatabaseMigration 2017-03-03 21:15:36,284 WARN [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-6) WFLYJCA0003: Unable to instantiate driver class "org.olap4j.driver.xmla.XmlaOlap4jDriver": java.lang.NoSuchMethodException: org.olap4j.driver.xmla.XmlaOlap4jDriver.() 2017-03-03 21:15:36,285 INFO [org.jboss.weld.deployer] (MSC service thread 1-6) WFLYWELD0006: Starting Services for CDI deployment: pvsketch.war 2017-03-03 21:15:36,319 INFO [org.jboss.weld.deployer] (MSC service thread 1-8) WFLYWELD0009: Starting weld service for deployment pvsketch.war 2017-03-03 21:15:36,802 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 22) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:15:36,803 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 22) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:36,812 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 22) Envers integration enabled? : true 2017-03-03 21:15:36,823 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 15) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:15:36,824 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 15) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:36,828 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 15) Envers integration enabled? : true 2017-03-03 21:15:36,845 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 22) WFLYJPA0010: Starting Persistence Unit (phase 2 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:15:36,846 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 22) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2017-03-03 21:15:36,851 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 22) Envers integration enabled? : true 2017-03-03 21:15:36,961 WARN [org.hibernate.boot.internal.SessionFactoryBuilderImpl] (ServerService Thread Pool -- 15) Unrecognized hbm2ddl_auto value : none. Supported values include create, create-drop, update, and validate. Ignoring 2017-03-03 21:15:37,226 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 15) HHH000397: Using ASTQueryTranslatorFactory 2017-03-03 21:15:37,298 INFO [org.jboss.weld.Event] (MSC service thread 1-7) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.message.MessageBundleExtension.detectInterfaces(@Observes ProcessAnnotatedType) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:37,310 INFO [org.jboss.weld.Event] (MSC service thread 1-7) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension.vetoBeans(@Observes ProcessAnnotatedType, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:37,315 INFO [org.jboss.weld.Event] (MSC service thread 1-7) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.interceptor.GlobalInterceptorExtension.promoteInterceptors(@Observes ProcessAnnotatedType, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2017-03-03 21:15:38,857 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (ServerService Thread Pool -- 15) EmailPeriodicTimer scheduled: ScheduleExpression[second=0,15,30,45 minute=* hour=* dayOfWeek=* dayOfMonth=* month=* year=* start=null end=null timezone=] 2017-03-03 21:15:39,073 INFO [com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer] (ServerService Thread Pool -- 15) EmailPeriodicTimer scheduled: ScheduleExpression[second=0 minute=0/5 hour=* dayOfWeek=* dayOfMonth=* month=* year=* start=null end=null timezone=] 2017-03-03 21:15:39,082 INFO [io.undertow.servlet] (ServerService Thread Pool -- 15) Initializing Shiro environment 2017-03-03 21:15:39,082 INFO [org.apache.shiro.web.env.EnvironmentLoader] (ServerService Thread Pool -- 15) Starting Shiro environment initialization. 2017-03-03 21:15:39,191 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 15) Init Infinispan cache manager 2017-03-03 21:15:39,191 INFO [org.apache.shiro.config.IniSecurityManagerFactory] (ServerService Thread Pool -- 15) Realms have been explicitly set on the SecurityManager instance - auto-setting of realms will not occur. 2017-03-03 21:15:39,195 INFO [org.apache.shiro.web.env.EnvironmentLoader] (ServerService Thread Pool -- 15) Shiro environment initialized in 112 ms. 2017-03-03 21:15:39,198 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 15) Initializing Mojarra 2.2.12-jbossorg-2 20150729-1131 for context '/pvsketch' 2017-03-03 21:15:41,322 WARN [org.jboss.as.weld] (ServerService Thread Pool -- 15) WFLYWELD0052: Using deployment classloader to load proxy classes for module org.jboss.resteasy.resteasy-jaxrs:main. Package-private access will not work. To fix this the module should declare dependencies on [org.jboss.weld.core, org.jboss.weld.spi] 2017-03-03 21:15:41,376 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002225: Deploying javax.ws.rs.core.Application: class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.UtilityProviderResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.RackingResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AutoPilotResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.SubscriptionResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.AppExceptionMapper from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.CustomerResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.TimeZoneResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AnonymousResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.PVModuleResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,377 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectLinkResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.BusinessResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.VersionResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.LocaleResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.StringJsonMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.UserResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.TestResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.InverterResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AdminResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,378 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ProjectDetailsResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.DefaultEquipmentResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ReportResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.BooleanMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.ManufacturerResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002200: Adding class resource com.pvcomplete.pvsketch.api.presentation.services.AuthResource from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,379 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 15) RESTEASY002205: Adding provider class com.pvcomplete.pvsketch.api.presentation.services.StringMessageBodyWriter from Application class com.pvcomplete.pvsketch.api.presentation.services.ApplicationConfig$Proxy$_$$_WeldClientProxy 2017-03-03 21:15:41,464 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 15) WFLYUT0021: Registered web context: /pvsketch 2017-03-03 21:15:41,512 INFO [org.jboss.as.server] (management-handler-thread - 2) WFLYSRV0010: Deployed "pvsketch.war" (runtime-name : "pvsketch.war") 2017-03-03 21:15:43,514 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-13) >>>> Constructor 2017-03-03 21:15:44,409 DEBUG [com.pvcomplete.pvsketch.api.core.util.ConfigurationFactory] (default task-13) Try to load properties file for Application 2017-03-03 21:15:45,002 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer start... 2017-03-03 21:15:45,214 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer end... 2017-03-03 21:15:45,653 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-13) Log before method call(public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@4f6c53e5)... 2017-03-03 21:15:45,854 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-13) Log after method call(exec. time=201 ms., public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:15:45,894 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-15) >>>> Constructor 2017-03-03 21:15:45,898 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-16) >>>> Constructor 2017-03-03 21:15:47,971 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-16) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@38192288)... 2017-03-03 21:15:47,971 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-15) Log before method call(public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@75391073)... 2017-03-03 21:15:48,178 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-16) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:15:48,178 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-15) Log after method call(exec. time=206 ms., public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:15:48,606 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-16) Log before method call(public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@64c47de8)... 2017-03-03 21:15:48,607 DEBUG [com.pvcomplete.pvsketch.api.presentation.services.AnonymousResource] (default task-16) error_role_not_allowed 2017-03-03 21:15:48,649 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-19) >>>> Constructor 2017-03-03 21:15:50,719 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-19) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@493b8573)... 2017-03-03 21:15:50,925 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-19) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:15:51,343 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-19) Log before method call(public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@53737cd6)... 2017-03-03 21:15:51,343 DEBUG [com.pvcomplete.pvsketch.api.presentation.services.AnonymousResource] (default task-19) error_role_not_allowed 2017-03-03 21:16:00,002 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 3) Email periodic timer start... 2017-03-03 21:16:00,210 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 3) Email periodic timer end... 2017-03-03 21:16:11,525 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-27) >>>> Constructor 2017-03-03 21:16:12,351 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultPasswordChangerFacade] (default task-27) >>>> Constructor 2017-03-03 21:16:12,768 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultPasswordChangerFacade] (default task-27) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:14,460 DEBUG [com.pvcomplete.pvsketch.api.presentation.services.AuthResource] (default task-27) Current user: true 2017-03-03 21:16:14,463 DEBUG [com.pvcomplete.pvsketch.api.core.security.JpaResetPasswordRealm] (default task-27) Principal collection: com.pvcomplete.pvsketch.api.model.entity.UserAccount[ id=130 ] 2017-03-03 21:16:14,531 DEBUG [com.pvcomplete.pvsketch.api.core.security.JpaRealm] (default task-27) Principal collection: com.pvcomplete.pvsketch.api.model.entity.UserAccount[ id=130 ] 2017-03-03 21:16:15,006 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 4) Email periodic timer start... 2017-03-03 21:16:15,167 DEBUG [com.pvcomplete.pvsketch.api.core.security.JpaRealm] (default task-27) pvsketch.admin@pvsketch.com roles: [WorkspaceAdmin] 2017-03-03 21:16:15,167 DEBUG [com.pvcomplete.pvsketch.api.core.security.JpaRealm] (default task-27) pvsketch.admin@pvsketch.com perms: [prj:edit, usr:login, ba:self:view, usr:create, prj:self:del, ba:self:edit, usr:view, usr:self:edit, prj:self:edit, usr:edit] 2017-03-03 21:16:15,168 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-27) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Role com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentRole() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@32b5539c)... 2017-03-03 21:16:15,215 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 4) Email periodic timer end... 2017-03-03 21:16:15,580 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-27) Log after method call(exec. time=412 ms., public com.pvcomplete.pvsketch.api.model.entity.Role com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentRole() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:16,520 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-51) >>>> Constructor 2017-03-03 21:16:17,295 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-49) >>>> Constructor 2017-03-03 21:16:17,333 DEBUG [com.pvcomplete.pvsketch.api.core.bl.AdminFacade] (default task-48) >>>> Constructor 2017-03-03 21:16:17,334 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAdminFacade] (default task-48) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:17,336 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-48) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@1bedcb23)... 2017-03-03 21:16:17,541 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-48) Log after method call(exec. time=205 ms., public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:18,121 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultPasswordChangerFacade] (default task-49) >>>> Constructor 2017-03-03 21:16:18,534 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultPasswordChangerFacade] (default task-49) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:18,592 DEBUG [com.pvcomplete.pvsketch.api.core.bl.AdminFacade] (default task-52) >>>> Constructor 2017-03-03 21:16:18,593 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAdminFacade] (default task-52) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:18,594 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-52) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@1b6a7348)... 2017-03-03 21:16:18,800 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-52) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:20,344 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-51) Log before method call(public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@fc75883)... 2017-03-03 21:16:20,541 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-51) Log after method call(exec. time=197 ms., public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:20,551 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-53) >>>> Constructor 2017-03-03 21:16:22,520 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-53) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@20a47ae5)... 2017-03-03 21:16:22,717 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-53) Log after method call(exec. time=197 ms., public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:23,114 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-53) Log before method call(public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@30256610)... 2017-03-03 21:16:24,125 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-53) Log after method call(exec. time=1011 ms., public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:24,973 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-54) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingManufacturers(), [Ljava.lang.Object;@267ea98d)... 2017-03-03 21:16:24,974 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (default task-54) HHH000397: Using ASTQueryTranslatorFactory 2017-03-03 21:16:25,013 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-56) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInverterManufacturers(), [Ljava.lang.Object;@30b79b32)... 2017-03-03 21:16:25,024 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-1) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUserCustomers() throws com.pvcomplete.pvsketch.api.model.exception.BLException, [Ljava.lang.Object;@5dd83f4c)... 2017-03-03 21:16:25,190 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-54) Log after method call(exec. time=217 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingManufacturers())... 2017-03-03 21:16:25,437 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-1) Log after method call(exec. time=412 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUserCustomers() throws com.pvcomplete.pvsketch.api.model.exception.BLException)... 2017-03-03 21:16:26,036 DEBUG [com.pvcomplete.pvsketch.api.core.bl.AdminFacade] (default task-59) >>>> Constructor 2017-03-03 21:16:26,037 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAdminFacade] (default task-59) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:26,037 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-59) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@263601e1)... 2017-03-03 21:16:26,243 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-59) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:26,273 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-61) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer), [Ljava.lang.Object;@476d6b60)... 2017-03-03 21:16:26,703 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-61) Log after method call(exec. time=429 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer))... 2017-03-03 21:16:26,791 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-57) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer), [Ljava.lang.Object;@77028b67)... 2017-03-03 21:16:26,792 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-60) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInvertersByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@254330ad)... 2017-03-03 21:16:26,801 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-58) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@5e70a0ef)... 2017-03-03 21:16:27,018 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-57) Log after method call(exec. time=225 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer))... 2017-03-03 21:16:27,308 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-5) Log before method call(public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException, [Ljava.lang.Object;@942bc9d)... 2017-03-03 21:16:27,318 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-5) SELECT e FROM Project e LEFT JOIN FETCH e.address a LEFT JOIN FETCH e.createdBy u LEFT JOIN FETCH e.customer c WHERE e.businessAccount.workspaceId = :workspaceId ORDER BY e.modifiedOn DESC 2017-03-03 21:16:27,340 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-56) Log after method call(exec. time=2327 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInverterManufacturers())... 2017-03-03 21:16:27,550 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-62) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingsByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@1f1d61b8)... 2017-03-03 21:16:27,621 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-5) SELECT count(e.projectId) FROM Project e LEFT JOIN e.address a LEFT JOIN e.customer c WHERE e.businessAccount.workspaceId = :workspaceId 2017-03-03 21:16:27,798 WARN [org.hibernate.procedure.internal.ProcedureCallImpl] (default task-62) HHH000456: Named parameters are used for a callable statement, but database metadata indicates named parameters are not supported. 2017-03-03 21:16:27,838 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-5) Log after method call(exec. time=529 ms., public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException)... 2017-03-03 21:16:29,089 WARN [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-62) SQL Error: 1054, SQLState: 42S22 2017-03-03 21:16:29,089 ERROR [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-62) Unknown column 'rackingpro0_.allowRowSummaryExport' in 'field list' 2017-03-03 21:16:29,092 ERROR [com.pvcomplete.pvsketch.api.presentation.services.AppExceptionMapper] (default task-62) RS exception: javax.persistence.PersistenceException: org.hibernate.exception.SQLGrammarException: could not extract ResultSet at org.hibernate.jpa.spi.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1692) at org.hibernate.jpa.spi.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1602) at org.hibernate.jpa.internal.QueryImpl.getResultList(QueryImpl.java:492) at org.hibernate.jpa.criteria.compile.CriteriaQueryTypeQueryAdapter.getResultList(CriteriaQueryTypeQueryAdapter.java:50) at com.pvcomplete.pvsketch.api.core.dal.repository.RepositoryBase.getAll(RepositoryBase.java:52) at com.pvcomplete.pvsketch.api.core.bl.builder.DefaultRackingDataBuilder.setRackingProperties(DefaultRackingDataBuilder.java:48) at com.pvcomplete.pvsketch.api.core.bl.builder.DefaultRackingDataBuilder.getByManufacturer(DefaultRackingDataBuilder.java:38) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingsByManufacturer(DefaultUserFacade.java:402) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade$Proxy$_$$_WeldSubclass.getRackingsByManufacturer$$super(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:49) at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:77) at com.pvcomplete.pvsketch.api.core.security.SecuredInterceptor.interceptShiroSecurity(SecuredInterceptor.java:77) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:74) at org.jboss.weld.interceptor.proxy.NonTerminalAroundInvokeInvocationContext.proceedInternal(NonTerminalAroundInvokeInvocationContext.java:64) at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:77) at com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor.logging(DefaultLogInterceptor.java:23) at sun.reflect.GeneratedMethodAccessor54.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:74) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeAroundInvoke(InterceptorMethodHandler.java:84) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeInterception(InterceptorMethodHandler.java:72) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.invoke(InterceptorMethodHandler.java:56) at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:79) at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:68) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade$Proxy$_$$_WeldSubclass.getRackingsByManufacturer(Unknown Source) at com.pvcomplete.pvsketch.api.presentation.services.RackingResource.getByManufacturerId(RackingResource.java:49) at com.pvcomplete.pvsketch.api.presentation.services.RackingResource$Proxy$_$$_WeldClientProxy.getByManufacturerId(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:139) at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:295) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:249) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:236) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:395) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:202) at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:221) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:51) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:85) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:129) at com.pvcomplete.pvsketch.api.presentation.services.SessionTimeoutCookieFilter.doFilter(SessionTimeoutCookieFilter.java:37) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at com.pvcomplete.pvsketch.api.presentation.services.ResponseHeaderFilter.doFilter(ResponseHeaderFilter.java:125) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:383) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84) at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62) at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36) at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:131) at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:51) at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46) at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64) at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:56) at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60) at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77) at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50) at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:284) at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:263) at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81) at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:174) at io.undertow.server.Connectors.executeRootHandler(Connectors.java:202) at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:793) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: org.hibernate.exception.SQLGrammarException: could not extract ResultSet at org.hibernate.exception.internal.SQLExceptionTypeDelegate.convert(SQLExceptionTypeDelegate.java:63) at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:42) at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:109) at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:95) at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:79) at org.hibernate.loader.Loader.getResultSet(Loader.java:2116) at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1899) at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1875) at org.hibernate.loader.Loader.doQuery(Loader.java:919) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:336) at org.hibernate.loader.Loader.doList(Loader.java:2611) at org.hibernate.loader.Loader.doList(Loader.java:2594) at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2423) at org.hibernate.loader.Loader.list(Loader.java:2418) at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:501) at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:371) at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:216) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1326) at org.hibernate.internal.QueryImpl.list(QueryImpl.java:87) at org.hibernate.jpa.internal.QueryImpl.list(QueryImpl.java:606) at org.hibernate.jpa.internal.QueryImpl.getResultList(QueryImpl.java:483) ... 98 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: Unknown column 'rackingpro0_.allowRowSummaryExport' in 'field list' at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at com.mysql.jdbc.Util.handleNewInstance(Util.java:404) at com.mysql.jdbc.Util.getInstance(Util.java:387) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:939) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3878) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2478) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2625) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2551) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1861) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:1962) at org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.executeQuery(WrappedPreparedStatement.java:504) at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:70) ... 114 more 2017-03-03 21:16:29,394 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-60) Log after method call(exec. time=2602 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInvertersByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:29,479 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-58) Log after method call(exec. time=2677 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:29,611 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-7) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@2490741e)... 2017-03-03 21:16:30,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 5) Email periodic timer start... 2017-03-03 21:16:30,211 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 5) Email periodic timer end... 2017-03-03 21:16:30,434 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-7) Log after method call(exec. time=823 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:31,243 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-6) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@1f6dc192)... 2017-03-03 21:16:32,058 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-6) Log after method call(exec. time=815 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:34,599 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-63) Log before method call(public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException, [Ljava.lang.Object;@777452f1)... 2017-03-03 21:16:34,600 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-63) SELECT e FROM Project e LEFT JOIN FETCH e.address a LEFT JOIN FETCH e.createdBy u LEFT JOIN FETCH e.customer c WHERE e.businessAccount.workspaceId = :workspaceId ORDER BY e.modifiedOn DESC 2017-03-03 21:16:34,810 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-63) SELECT count(e.projectId) FROM Project e LEFT JOIN e.address a LEFT JOIN e.customer c WHERE e.businessAccount.workspaceId = :workspaceId 2017-03-03 21:16:35,014 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-63) Log after method call(exec. time=415 ms., public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException)... 2017-03-03 21:16:37,420 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-64) >>>> Constructor 2017-03-03 21:16:39,424 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-64) Log before method call(public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@1e3dff1a)... 2017-03-03 21:16:39,620 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-64) Log after method call(exec. time=196 ms., public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:39,626 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-10) >>>> Constructor 2017-03-03 21:16:39,630 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-9) >>>> Constructor 2017-03-03 21:16:41,640 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-10) Log before method call(public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@20f33689)... 2017-03-03 21:16:41,692 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-9) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@249fe63c)... 2017-03-03 21:16:41,846 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-10) Log after method call(exec. time=206 ms., public boolean com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.checkWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:41,899 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-9) Log after method call(exec. time=205 ms., public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:42,315 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-9) Log before method call(public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@6e1dcf09)... 2017-03-03 21:16:43,347 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-9) Log after method call(exec. time=1032 ms., public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:43,354 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade] (default task-14) >>>> Constructor 2017-03-03 21:16:45,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 6) Email periodic timer start... 2017-03-03 21:16:45,203 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 6) Email periodic timer end... 2017-03-03 21:16:45,424 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-14) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@4827a49b)... 2017-03-03 21:16:45,631 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-14) Log after method call(exec. time=207 ms., public com.pvcomplete.pvsketch.api.model.entity.Workspace com.pvcomplete.pvsketch.api.core.bl.DefaultAnonymousFacade.getWorkspaceByCode(java.lang.String) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:46,047 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-14) Log before method call(public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@334793c4)... 2017-03-03 21:16:47,079 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-14) Log after method call(exec. time=1032 ms., public com.pvcomplete.pvsketch.api.presentation.dto.DefaultEquipmentDTO com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getDefaultEquipment() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:47,985 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-12) Log before method call(public com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectDetails(java.lang.Long) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@28e210ba)... 2017-03-03 21:16:48,147 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-18) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer), [Ljava.lang.Object;@4be6fded)... 2017-03-03 21:16:48,177 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-15) Log before method call(public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException, [Ljava.lang.Object;@9794b22)... 2017-03-03 21:16:48,177 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-15) SELECT e FROM Project e LEFT JOIN FETCH e.address a LEFT JOIN FETCH e.createdBy u LEFT JOIN FETCH e.customer c WHERE e.businessAccount.workspaceId = :workspaceId ORDER BY e.modifiedOn DESC 2017-03-03 21:16:48,376 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-18) Log after method call(exec. time=228 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer))... 2017-03-03 21:16:48,377 INFO [com.pvcomplete.pvsketch.api.core.dal.util.QueryHelper] (default task-15) SELECT count(e.projectId) FROM Project e LEFT JOIN e.address a LEFT JOIN e.customer c WHERE e.businessAccount.workspaceId = :workspaceId 2017-03-03 21:16:48,580 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-15) Log after method call(exec. time=403 ms., public com.pvcomplete.pvsketch.api.model.utils.ResultList com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectList(com.pvcomplete.pvsketch.api.model.filter.ProjectListFilter) throws com.pvcomplete.pvsketch.api.model.exception.ValidationException)... 2017-03-03 21:16:49,231 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-16) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@2b5f2bba)... 2017-03-03 21:16:49,841 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-16) Log after method call(exec. time=610 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:50,072 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-12) Log after method call(exec. time=2087 ms., public com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectDetails(java.lang.Long) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:50,904 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-21) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingManufacturers(), [Ljava.lang.Object;@54bdd1b0)... 2017-03-03 21:16:50,907 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-24) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInvertersByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@5d7425d6)... 2017-03-03 21:16:50,915 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-22) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInverterManufacturers(), [Ljava.lang.Object;@50d76562)... 2017-03-03 21:16:50,934 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-23) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@25e0480a)... 2017-03-03 21:16:50,938 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-25) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUserCustomers() throws com.pvcomplete.pvsketch.api.model.exception.BLException, [Ljava.lang.Object;@12474029)... 2017-03-03 21:16:50,938 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-11) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer), [Ljava.lang.Object;@6e7904d)... 2017-03-03 21:16:51,106 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-21) Log after method call(exec. time=202 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingManufacturers())... 2017-03-03 21:16:51,124 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-22) Log after method call(exec. time=209 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInverterManufacturers())... 2017-03-03 21:16:51,351 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-25) Log after method call(exec. time=413 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUserCustomers() throws com.pvcomplete.pvsketch.api.model.exception.BLException)... 2017-03-03 21:16:51,514 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-24) Log after method call(exec. time=607 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getInvertersByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:51,733 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-23) Log after method call(exec. time=799 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:51,928 DEBUG [com.pvcomplete.pvsketch.api.core.bl.AdminFacade] (default task-27) >>>> Constructor 2017-03-03 21:16:51,929 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAdminFacade] (default task-27) >>>>>>>>>> Post Constructor... 2017-03-03 21:16:51,930 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-27) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@111ef6e5)... 2017-03-03 21:16:51,942 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-19) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingsByManufacturer(java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@cc423dd)... 2017-03-03 21:16:52,136 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-27) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:52,149 WARN [org.hibernate.procedure.internal.ProcedureCallImpl] (default task-19) HHH000456: Named parameters are used for a callable statement, but database metadata indicates named parameters are not supported. 2017-03-03 21:16:52,189 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-26) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer), [Ljava.lang.Object;@c112f37)... 2017-03-03 21:16:52,615 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-26) Log after method call(exec. time=425 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer))... 2017-03-03 21:16:53,169 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-11) Log after method call(exec. time=2231 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesManufacturers(java.lang.Integer))... 2017-03-03 21:16:53,389 WARN [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-19) SQL Error: 1054, SQLState: 42S22 2017-03-03 21:16:53,390 ERROR [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-19) Unknown column 'rackingpro0_.allowRowSummaryExport' in 'field list' 2017-03-03 21:16:53,390 ERROR [com.pvcomplete.pvsketch.api.presentation.services.AppExceptionMapper] (default task-19) RS exception: javax.persistence.PersistenceException: org.hibernate.exception.SQLGrammarException: could not extract ResultSet at org.hibernate.jpa.spi.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1692) at org.hibernate.jpa.spi.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1602) at org.hibernate.jpa.internal.QueryImpl.getResultList(QueryImpl.java:492) at org.hibernate.jpa.criteria.compile.CriteriaQueryTypeQueryAdapter.getResultList(CriteriaQueryTypeQueryAdapter.java:50) at com.pvcomplete.pvsketch.api.core.dal.repository.RepositoryBase.getAll(RepositoryBase.java:52) at com.pvcomplete.pvsketch.api.core.bl.builder.DefaultRackingDataBuilder.setRackingProperties(DefaultRackingDataBuilder.java:48) at com.pvcomplete.pvsketch.api.core.bl.builder.DefaultRackingDataBuilder.getByManufacturer(DefaultRackingDataBuilder.java:38) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getRackingsByManufacturer(DefaultUserFacade.java:402) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade$Proxy$_$$_WeldSubclass.getRackingsByManufacturer$$super(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:49) at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:77) at com.pvcomplete.pvsketch.api.core.security.SecuredInterceptor.interceptShiroSecurity(SecuredInterceptor.java:77) at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:74) at org.jboss.weld.interceptor.proxy.NonTerminalAroundInvokeInvocationContext.proceedInternal(NonTerminalAroundInvokeInvocationContext.java:64) at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:77) at com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor.logging(DefaultLogInterceptor.java:23) at sun.reflect.GeneratedMethodAccessor54.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:74) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeAroundInvoke(InterceptorMethodHandler.java:84) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeInterception(InterceptorMethodHandler.java:72) at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.invoke(InterceptorMethodHandler.java:56) at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:79) at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:68) at com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade$Proxy$_$$_WeldSubclass.getRackingsByManufacturer(Unknown Source) at com.pvcomplete.pvsketch.api.presentation.services.RackingResource.getByManufacturerId(RackingResource.java:49) at com.pvcomplete.pvsketch.api.presentation.services.RackingResource$Proxy$_$$_WeldClientProxy.getByManufacturerId(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:139) at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:295) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:249) at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:236) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:395) at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:202) at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:221) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56) at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:51) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:85) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:129) at com.pvcomplete.pvsketch.api.presentation.services.SessionTimeoutCookieFilter.doFilter(SessionTimeoutCookieFilter.java:37) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at com.pvcomplete.pvsketch.api.presentation.services.ResponseHeaderFilter.doFilter(ResponseHeaderFilter.java:125) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:61) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.web.servlet.ProxiedFilterChain.doFilter(ProxiedFilterChain.java:66) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:383) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:60) at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131) at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84) at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62) at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36) at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:131) at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:51) at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46) at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64) at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:56) at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60) at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77) at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50) at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43) at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:284) at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:263) at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81) at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:174) at io.undertow.server.Connectors.executeRootHandler(Connectors.java:202) at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:793) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: org.hibernate.exception.SQLGrammarException: could not extract ResultSet at org.hibernate.exception.internal.SQLExceptionTypeDelegate.convert(SQLExceptionTypeDelegate.java:63) at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:42) at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:109) at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:95) at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:79) at org.hibernate.loader.Loader.getResultSet(Loader.java:2116) at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1899) at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1875) at org.hibernate.loader.Loader.doQuery(Loader.java:919) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:336) at org.hibernate.loader.Loader.doList(Loader.java:2611) at org.hibernate.loader.Loader.doList(Loader.java:2594) at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2423) at org.hibernate.loader.Loader.list(Loader.java:2418) at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:501) at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:371) at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:216) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1326) at org.hibernate.internal.QueryImpl.list(QueryImpl.java:87) at org.hibernate.jpa.internal.QueryImpl.list(QueryImpl.java:606) at org.hibernate.jpa.internal.QueryImpl.getResultList(QueryImpl.java:483) ... 97 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: Unknown column 'rackingpro0_.allowRowSummaryExport' in 'field list' at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at com.mysql.jdbc.Util.handleNewInstance(Util.java:404) at com.mysql.jdbc.Util.getInstance(Util.java:387) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:939) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3878) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2478) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2625) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2551) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1861) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:1962) at org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.executeQuery(WrappedPreparedStatement.java:504) at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:70) ... 113 more 2017-03-03 21:16:53,533 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-29) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@57d0cf43)... 2017-03-03 21:16:54,557 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-29) Log after method call(exec. time=1023 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:55,113 INFO [com.pvcomplete.pvsketch.api.presentation.services.ProjectResource] (default task-28) >>>>>>>>>>>>>>>>> before sending request 2017-03-03 21:16:55,310 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-28) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.BusinessAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentBusinessAccount() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@74a21cdb)... 2017-03-03 21:16:55,375 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-30) Log before method call(public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@61a3e968)... 2017-03-03 21:16:55,516 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-28) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.BusinessAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentBusinessAccount() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:55,930 INFO [com.pvcomplete.pvsketch.api.presentation.services.ProjectResource] (default task-28) >>>>>>>>>>>>>>>>> sending request to http://52.38.22.167/APIService/layout/edgepoints/project 2017-03-03 21:16:55,930 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-28) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.BusinessAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentBusinessAccount() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@13c0fdde)... 2017-03-03 21:16:55,930 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-28) Log after method call(exec. time=0 ms., public com.pvcomplete.pvsketch.api.model.entity.BusinessAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentBusinessAccount() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:56,398 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-30) Log after method call(exec. time=1023 ms., public java.util.List com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getPVModulesByManufacturer(java.lang.Integer,java.lang.Integer) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:16:57,185 INFO [com.pvcomplete.pvsketch.api.presentation.services.ProjectResource] (default task-28) >>>>>>>>>>>>>>>>> after sending request 2017-03-03 21:16:58,348 INFO [com.pvcomplete.pvsketch.api.presentation.services.ProjectDetailsResource] (default task-31) Requesting annual production simulation per roof. 2017-03-03 21:16:58,353 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-31) Log before method call(public com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationResult[] com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectAnnualProductionByRoof(com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@7264c49f)... 2017-03-03 21:16:59,372 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) >>>> Building simulation URL: type - annual, URL - http://52.38.22.167/SAMService/api/simulate/annual 2017-03-03 21:16:59,986 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) >>>>>>>>>>>>>> Has racking configurations: false. Row count: 1 2017-03-03 21:16:59,986 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) >>>>>>>>>>>>>> Building sub array 0 2017-03-03 21:16:59,987 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) >>>>>>>>>>>>>> Sub array count 1 2017-03-03 21:16:59,995 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) Running simulation for request : {"latitude":37.806692,"longitude":-122.269036,"azimuth":0.0,"inverterCount":9,"inverterPowerRatio":1.0,"inverterId":178,"pvModuleId":83,"rackingId":18,"modulesPerString":10,"clientType":"PVSKETCH","tag":"ProjectID: 3643, versionId: 4155, roof id: null","subArrays":[{"stringCount":11,"tilt":0.0,"slope":0.0,"soiling":3.0,"layout":{"n2nRowSpacing":1.0,"moduleSpacing":1.0,"rotationAngle":0.0,"gcr":1.0,"moduleQtyAlongSide":1,"orientation":1,"moduleHeight":76.93000030517578,"moduleWidth":38.65999984741211,"layoutUnits":null},"layoutAzimuth":336.0}],"simulationModel":"PVWatts","systemLosses":14.0,"inverterEfficiency":96.0} 2017-03-03 21:17:00,011 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 7) Email periodic timer start... 2017-03-03 21:17:00,209 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 7) Email periodic timer end... 2017-03-03 21:17:04,021 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) Simulation status code: OK (200) 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) >>>>>>> layout unit size: layout version id 4155, version id: 4155 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) >>>>>>> layout unit size: roofid null, layout: null, module count: 109 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) >>>>>>> actual module count: 109, simulated module count: 110 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (Thread-105) >>>>>>> annual energy: 39247.53 annual energy converted: 38890.73427272728 conversion factor: 0.990909090909091 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) ============================= results count: 1 2017-03-03 21:17:04,024 INFO [com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationAppServiceImpl] (default task-31) ============================= results (0) monthly energy exists: true 2017-03-03 21:17:04,024 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-31) Log after method call(exec. time=5671 ms., public com.pvcomplete.pvsketch.api.core.bl.appservice.simulation.SimulationResult[] com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getProjectAnnualProductionByRoof(com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:17:04,911 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-32) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.Project com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.updateProjectRoofFacesOriented(com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData) throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@7e456abd)... 2017-03-03 21:17:04,912 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultSubscriptionProcessInterceptor] (default task-32) DefaultSubscriptionProcessInterceptor 1 2017-03-03 21:17:08,893 DEBUG [com.pvcomplete.pvsketch.api.core.bl.workflow.ProjectDetailsUpdateWorkflowImpl] (default task-32) >>>>>>>>>>>>>>>>>>>> updVersions 1: 1 2017-03-03 21:17:08,893 DEBUG [com.pvcomplete.pvsketch.api.core.bl.workflow.ProjectDetailsUpdateWorkflowImpl] (default task-32) >>>>>>>>>>>>>>>>>>>> updVersions 2: 4155 2017-03-03 21:17:12,777 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-32) Log after method call(exec. time=7866 ms., public com.pvcomplete.pvsketch.api.model.entity.Project com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.updateProjectRoofFacesOriented(com.pvcomplete.pvsketch.api.model.data.ProjectDetailsData) throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:17:12,777 DEBUG [com.pvcomplete.pvsketch.api.presentation.services.ProjectDetailsResource] (default task-32) >>>>>>>>>>>>>>> Current Version id:4155 2017-03-03 21:17:13,807 DEBUG [com.pvcomplete.pvsketch.api.core.bl.AdminFacade] (default task-41) >>>> Constructor 2017-03-03 21:17:13,807 DEBUG [com.pvcomplete.pvsketch.api.core.bl.DefaultAdminFacade] (default task-41) >>>>>>>>>> Post Constructor... 2017-03-03 21:17:13,807 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-41) Log before method call(public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException, [Ljava.lang.Object;@235a1c76)... 2017-03-03 21:17:14,013 DEBUG [com.pvcomplete.pvsketch.api.core.util.DefaultLogInterceptor] (default task-41) Log after method call(exec. time=206 ms., public com.pvcomplete.pvsketch.api.model.entity.UserAccount com.pvcomplete.pvsketch.api.core.bl.DefaultUserFacade.getCurrentUser() throws com.pvcomplete.pvsketch.api.model.exception.AppException)... 2017-03-03 21:17:15,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 8) Email periodic timer start... 2017-03-03 21:17:15,204 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 8) Email periodic timer end... 2017-03-03 21:17:30,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 9) Email periodic timer start... 2017-03-03 21:17:30,200 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 9) Email periodic timer end... 2017-03-03 21:17:45,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 10) Email periodic timer start... 2017-03-03 21:17:45,206 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 10) Email periodic timer end... 2017-03-03 21:18:00,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer start... 2017-03-03 21:18:00,201 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer end... 2017-03-03 21:18:15,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer start... 2017-03-03 21:18:15,197 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer end... 2017-03-03 21:18:30,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 3) Email periodic timer start... 2017-03-03 21:18:30,202 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 3) Email periodic timer end... 2017-03-03 21:18:45,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 4) Email periodic timer start... 2017-03-03 21:18:45,198 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 4) Email periodic timer end... 2017-03-03 21:19:00,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 5) Email periodic timer start... 2017-03-03 21:19:00,204 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 5) Email periodic timer end... 2017-03-03 21:19:15,000 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 6) Email periodic timer start... 2017-03-03 21:19:15,199 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 6) Email periodic timer end... 2017-03-03 21:19:30,002 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 7) Email periodic timer start... 2017-03-03 21:19:30,205 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 7) Email periodic timer end... 2017-03-03 21:19:45,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 8) Email periodic timer start... 2017-03-03 21:19:45,201 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 8) Email periodic timer end... 2017-03-03 21:20:00,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 10) Email periodic timer start... 2017-03-03 21:20:00,002 INFO [com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer] (EJB default - 9) Subscription periodic timer start... 2017-03-03 21:20:00,206 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 10) Email periodic timer end... 2017-03-03 21:20:01,465 INFO [com.pvcomplete.pvsketch.api.core.util.SubscriptionPeriodicTimer] (EJB default - 9) Subscription periodic timer end... 2017-03-03 21:20:15,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer start... 2017-03-03 21:20:15,413 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 1) Email periodic timer end... 2017-03-03 21:20:30,001 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer start... 2017-03-03 21:20:30,201 INFO [com.pvcomplete.pvsketch.api.core.util.EmailPeriodicTimer] (EJB default - 2) Email periodic timer end... 2017-03-03 21:20:37,794 INFO [org.jboss.as.server] (management-handler-thread - 5) WFLYSRV0211: Suspending server with 0ms timeout. 2017-03-03 21:20:37,799 INFO [org.jboss.as.server] (Thread-2) WFLYSRV0220: Server shutdown has been requested. 2017-03-03 21:20:37,838 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 66) WFLYUT0022: Unregistered web context: /pvsketch 2017-03-03 21:20:37,839 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) WFLYJCA0010: Unbound data source [java:jboss/datasources/pvcomplete_amazon] 2017-03-03 21:20:37,861 INFO [io.undertow.servlet] (ServerService Thread Pool -- 66) Cleaning up Shiro Environment 2017-03-03 21:20:37,861 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 66) Destroy Infinispan cache manager 2017-03-03 21:20:37,861 DEBUG [com.pvcomplete.pvsketch.api.core.security.InfinispanCacheManager] (ServerService Thread Pool -- 66) Destroy Infinispan cache manager 2017-03-03 21:20:37,864 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-5) WFLYCLINF0003: Stopped jpaRealm.authorizationCache cache from api container 2017-03-03 21:20:37,871 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0019: Host default-host stopping 2017-03-03 21:20:37,877 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-5) WFLYCLINF0003: Stopped jpaResetPasswordRealm.authorizationCache cache from api container 2017-03-03 21:20:37,879 INFO [org.jboss.weld.deployer] (MSC service thread 1-3) WFLYWELD0010: Stopping weld service for deployment pvsketch.war 2017-03-03 21:20:37,901 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS] 2017-03-03 21:20:37,905 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 85) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:20:37,905 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 71) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:20:37,905 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 69) WFLYJPA0011: Stopping Persistence Unit (phase 2 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:20:37,906 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-6) WFLYJCA0019: Stopped Driver service with driver-name = h2 2017-03-03 21:20:37,909 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 71) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#hardwareDBPU' 2017-03-03 21:20:37,910 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 85) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvsketchPrimaryPU' 2017-03-03 21:20:37,910 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) WFLYJCA0010: Unbound data source [java:jboss/datasources/hardware] 2017-03-03 21:20:37,914 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 69) WFLYJPA0011: Stopping Persistence Unit (phase 1 of 2) Service 'pvsketch.war#pvMigrationPU' 2017-03-03 21:20:37,914 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) WFLYJCA0010: Unbound data source [java:jboss/datasources/pvcomplete] 2017-03-03 21:20:37,925 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-3) WFLYJCA0019: Stopped Driver service with driver-name = MySqlJDBCDriver 2017-03-03 21:20:37,929 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0008: Undertow HTTP listener default suspending 2017-03-03 21:20:37,930 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0007: Undertow HTTP listener default stopped, was bound to 0.0.0.0:8080 2017-03-03 21:20:37,931 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0004: Undertow 1.3.15.Final stopping