Gateway is not starting

burak.ozcan · June 2, 2020, 1:04pm

Ignition service is running but gateway is not starting. Sharing the wrapper below.
Any suggesstion?

INFO   | jvm 1    | 2020/06/02 15:38:46 | E [IgnitionGateway               ] [12:38:46]: Error shutting down HttpClientManager. 
INFO   | jvm 1    | 2020/06/02 15:38:46 | java.lang.IllegalStateException: Cannot call shutdown, never started.
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at com.inductiveautomation.ignition.common.lifecycle.AbstractLifecycle.shutdown(AbstractLifecycle.java:36)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.shutdownInternal(IgnitionGateway.java:1867)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.shutdown(IgnitionGateway.java:2289)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at com.inductiveautomation.ignition.gateway.web.IgnitionWebAppImpl.onDestroy(IgnitionWebAppImpl.java:98)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.apache.wicket.Application.internalDestroy(Application.java:665)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.apache.wicket.protocol.http.WebApplication.internalDestroy(WebApplication.java:613)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.apache.wicket.protocol.http.WicketFilter.destroy(WicketFilter.java:528)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at com.inductiveautomation.ignition.gateway.bootstrap.GatewayFilter.destroy(GatewayFilter.java:67)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.servlet.FilterHolder.destroyInstance(FilterHolder.java:164)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.servlet.FilterHolder.doStop(FilterHolder.java:148)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.servlet.ServletHandler.doStop(ServletHandler.java:238)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.security.SecurityHandler.doStop(SecurityHandler.java:425)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.security.ConstraintSecurityHandler.doStop(ConstraintSecurityHandler.java:425)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.session.SessionHandler.doStop(SessionHandler.java:519)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.ContextHandler.stopContext(ContextHandler.java:916)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.servlet.ServletContextHandler.stopContext(ServletContextHandler.java:367)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.webapp.WebAppContext.stopWebapp(WebAppContext.java:1450)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.webapp.WebAppContext.stopContext(WebAppContext.java:1415)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:980)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:284)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.webapp.WebAppContext.doStop(WebAppContext.java:547)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:111)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.server.Server.doStop(Server.java:454)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:46 | 	at org.eclipse.jetty.util.thread.ShutdownThread.run(ShutdownThread.java:127)
INFO   | jvm 1    | 2020/06/02 15:38:46 | I [IgnitionGateway               ] [12:38:46]: Ignition Gateway shut down in 108ms 
INFO   | jvm 1    | 2020/06/02 15:38:46 | 15:38:46,265 |-INFO in ch.qos.logback.classic.AsyncAppender[SysoutAsync] - Queue flush finished successfully within timeout.
INFO   | jvm 1    | 2020/06/02 15:38:46 | 15:38:46,265 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Worker thread will flush remaining events before exiting. 
INFO   | jvm 1    | 2020/06/02 15:38:46 | 15:38:46,265 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Queue flush finished successfully within timeout.
STATUS | wrapper  | 2020/06/02 15:38:46 | <-- Wrapper Stopped
STATUS | wrapper  | 2020/06/02 15:38:49 | --> Wrapper Started as Service
STATUS | wrapper  | 2020/06/02 15:38:49 | Java Service Wrapper Standard Edition 64-bit 3.5.35
STATUS | wrapper  | 2020/06/02 15:38:49 |   Copyright (C) 1999-2018 Tanuki Software, Ltd. All Rights Reserved.
STATUS | wrapper  | 2020/06/02 15:38:49 |     http://wrapper.tanukisoftware.com
STATUS | wrapper  | 2020/06/02 15:38:49 |   Licensed to Inductive Automation for Inductive Automation
STATUS | wrapper  | 2020/06/02 15:38:49 | 
STATUS | wrapper  | 2020/06/02 15:38:49 | Launching a JVM...
INFO   | jvm 1    | 2020/06/02 15:38:49 | WrapperManager: Initializing...
INFO   | jvm 1    | 2020/06/02 15:38:50 | JVM failed to load language pack 'wrapperjni_tr.mo' from folder "../lang"
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,535 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [data\\logback.xml] at [file:/C:/Program%20Files/Inductive%20Automation/Ignition/data/logback.xml]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,645 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,648 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SysoutAppender]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,655 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,680 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [com.inductiveautomation.logging.SQLiteAppender]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,691 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DB]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,942 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@fdc183a - Driver name=SQLite JDBC
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,942 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@fdc183a - Driver version=3.23.1
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,942 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@fdc183a - supportsGetGeneratedKeys=true
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,952 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,953 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SysoutAsync]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,954 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SysoutAppender] to ch.qos.logback.classic.AsyncAppender[SysoutAsync]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,954 |-INFO in ch.qos.logback.classic.AsyncAppender[SysoutAsync] - Attaching appender named [SysoutAppender] to AsyncAppender.
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,955 |-INFO in ch.qos.logback.classic.AsyncAppender[SysoutAsync] - Setting discardingThreshold to 51
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,955 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,955 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DBAsync]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,956 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [DB] to ch.qos.logback.classic.AsyncAppender[DBAsync]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,956 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Attaching appender named [DB] to AsyncAppender.
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,956 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Setting discardingThreshold to 51
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,957 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,960 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SysoutAsync] to Logger[ROOT]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,960 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [DBAsync] to Logger[ROOT]
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,960 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
INFO   | jvm 1    | 2020/06/02 15:38:50 | 15:38:50,961 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4f60b738 - Registering current configuration as safe fallback point
INFO   | jvm 1    | 2020/06/02 15:38:51 | W [WebResourceManagerImpl        ] [12:38:51]: Unable to read "gateway.metroKeystoreAlias" gateway.xml property. Assuming metro keystore alias is: metro-key 
INFO   | jvm 1    | 2020/06/02 15:38:51 | I [o.e.j.u.log                   ] [12:38:51]: Logging initialized @1630ms to org.eclipse.jetty.util.log.Slf4jLog 
INFO   | jvm 1    | 2020/06/02 15:38:51 | I [g.WebServerManager            ] [12:38:51]: Starting setup 
INFO   | jvm 1    | 2020/06/02 15:38:51 | I [g.WebServerManager            ] [12:38:51]: Setup complete 
INFO   | jvm 1    | 2020/06/02 15:38:51 | I [g.WebServerManager            ] [12:38:51]: Starting up 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.Server                ] [12:38:51]: jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10-LTS 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.w.StandardDescriptorProcessor] [12:38:52]: NO JSP Support for /idp, did not find org.apache.jasper.servlet.JspServlet 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.session               ] [12:38:52]: DefaultSessionIdManager workerName=node0 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.session               ] [12:38:52]: No SessionScavenger set, using defaults 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.session               ] [12:38:52]: node0 Scavenging every 600000ms 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [g.IgnitionIdentityProvider    ] [12:38:52]: Initializing 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.h.ContextHandler      ] [12:38:52]: Started c.i.c.IdpWebAppContext@7d16876d{Ignition Identity Provider,/idp,file:///C:/Program%20Files/Inductive%20Automation/Ignition/webserver/webapps/idp/,AVAILABLE} 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.w.StandardDescriptorProcessor] [12:38:52]: NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.Application             ] [12:38:52]: [WicketFilter] init: Wicket core library initializer 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=IBehaviorListener, method=public abstract void org.apache.wicket.behavior.IBehaviorListener.onRequest()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=IFormSubmitListener, method=public abstract void org.apache.wicket.markup.html.form.IFormSubmitListener.onFormSubmitted()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=ILinkListener, method=public abstract void org.apache.wicket.markup.html.link.ILinkListener.onLinkClicked()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=IOnChangeListener, method=public abstract void org.apache.wicket.markup.html.form.IOnChangeListener.onSelectionChanged()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=IRedirectListener, method=public abstract void org.apache.wicket.IRedirectListener.onRedirect()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.RequestListenerInterface] [12:38:52]: registered listener interface [RequestListenerInterface name=IResourceListener, method=public abstract void org.apache.wicket.IResourceListener.onResourceRequested()] 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.Application             ] [12:38:52]: [WicketFilter] init: Wicket extensions initializer 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [g.IgnitionIdentityProvider    ] [12:38:52]: Setting Ignition Gateway 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [IgnitionGateway               ] [12:38:52]: Ignition[state=STOPPED] ContextState = STARTING 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.a.w.p.h.WebApplication      ] [12:38:52]: [WicketFilter] Started Wicket version 6.1.1 in DEPLOYMENT mode 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [IgnitionGateway               ] [12:38:52]: Starting Ignition 8.0.12 (b2020042115) 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [IgnitionGateway               ] [12:38:52]: Reloading JDBC classloader. 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [C.SecureRandomProvider        ] [12:38:52]: Secure random seed generated in 1ms 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [R.S.S.InternalDb              ] [12:38:52]: Internal db synchronizer disk cache initialized. State id= 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [R.StateMonitoring             ] [12:38:52]: Redundancy state changed: Role=Independent, Activity level=Active, Project state=Good, History level=Full 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [o.e.j.s.h.ContextHandler      ] [12:38:52]: Started c.i.c.MainWebAppContext@755fd3b5{Ignition,/,file:///C:/Program%20Files/Inductive%20Automation/Ignition/webserver/webapps/main/,AVAILABLE} 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [g.InternalDatabaseManager     ] [12:38:52]: Starting up... 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [P.InternalDatabase            ] [12:38:52]: Looking for existing internal database "config.idb"... 
INFO   | jvm 1    | 2020/06/02 15:38:52 | I [P.InternalDatabase            ] [12:38:52]: ... found existing. 
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [P.InternalDatabase            ] [12:38:52]: internal database "config.idb" started up successfully. 
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [P.InternalDatabase            ] [12:38:52]: Registering autobackup task [owner=Internal DB Autobackup, name=internal database "config.idb"] 
INFO   | jvm 1    | 2020/06/02 15:38:53 | WARNING: An illegal reflective access operation has occurred
INFO   | jvm 1    | 2020/06/02 15:38:53 | WARNING: Illegal reflective access by com.inductiveautomation.ignition.gateway.modules.ModuleManagerImpl (file:/C:/Program%20Files/Inductive%20Automation/Ignition/lib/core/gateway/gateway-8.0.12.jar) to field java.lang.ClassLoader.usr_paths
INFO   | jvm 1    | 2020/06/02 15:38:53 | WARNING: Please consider reporting this to the maintainers of com.inductiveautomation.ignition.gateway.modules.ModuleManagerImpl
INFO   | jvm 1    | 2020/06/02 15:38:53 | WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
INFO   | jvm 1    | 2020/06/02 15:38:53 | WARNING: All illegal access operations will be denied in a future release
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [g.ModuleManager               ] [12:38:53]: Loading modules.... 
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [o.e.j.s.AbstractConnector     ] [12:38:53]: Started ServerConnector@463315f3{SSL,[ssl, http/1.1]}{0.0.0.0:8060} 
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [o.e.j.s.AbstractConnector     ] [12:38:53]: Started ServerConnector@21330b6a{HTTP/1.1,[http/1.1]}{0.0.0.0:8088} 
INFO   | jvm 1    | 2020/06/02 15:38:53 | I [o.e.j.s.Server                ] [12:38:53]: Started @3853ms 
INFO   | jvm 1    | 2020/06/02 15:38:57 | I [A.Notification                ] [12:38:57]: Alert notification system created. 
INFO   | jvm 1    | 2020/06/02 15:38:57 | I [g.PersistentRecordSecurityLevelConfigService] [12:38:57]: Set up in 5 ms 
INFO   | jvm 1    | 2020/06/02 15:38:57 | I [g.PersistentRecordIdpAdapterConfigService] [12:38:57]: Set up in 7 ms 
INFO   | jvm 1    | 2020/06/02 15:38:57 | I [g.PersistentRecordIdpAdapterMetricsService] [12:38:57]: Set up in 8 ms 
INFO   | jvm 1    | 2020/06/02 15:38:58 | I [c.i.i.g.p.r.ProjectRecordConverter] [12:38:58]: Conversion finished. Elapsed time: 23 ms 
INFO   | jvm 1    | 2020/06/02 15:38:59 | E [C.BasicExecutionEngine        ] [12:38:59]: One-shot task com.inductiveautomation.ignition.gateway.IgnitionGateway$$Lambda$301/0x00000001003cfc40@35d4c9ac threw uncaught exception. 
INFO   | jvm 1    | 2020/06/02 15:38:59 | java.lang.ExceptionInInitializerError: null
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.init(PyType.java:686)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.createType(PyType.java:1573)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.addFromClass(PyType.java:1508)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.fromClass(PyType.java:1609)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.fromClass(PyType.java:1587)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyStringMap.getLazyType(PyStringMap.java:68)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyStringMap.<init>(PyStringMap.java:48)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyStringMap.<init>(PyStringMap.java:44)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.expose.BaseTypeBuilder.getDict(BaseTypeBuilder.java:49)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.init(PyType.java:674)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.addBuilder(PyType.java:1498)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.<clinit>(PyType.java)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyObject.<init>(PyObject.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyBuiltinCallable.<init>(PyBuiltinCallable.java:22)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyBuiltinMethod.<init>(PyBuiltinMethod.java:16)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyBuiltinMethodNarrow.<init>(PyBuiltinMethodNarrow.java:23)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyDataDescr$getset_descriptor___get___exposer.<init>(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyDataDescr$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyDataDescr.<clinit>(PyDataDescr.java)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyBuiltinCallable$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyBuiltinCallable.<clinit>(PyBuiltinCallable.java)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyObject$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyObject.<clinit>(PyObject.java)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.common.script.ScriptManager.asynchInit(ScriptManager.java:207)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.common.script.ScriptManager.<init>(ScriptManager.java:118)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.createScriptManager(IgnitionGateway.java:1266)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.script.GatewayScriptManagerHolder.initializeBeforeModuleSetup(GatewayScriptManagerHolder.java:67)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.startupInternal(IgnitionGateway.java:1021)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.redundancy.RedundancyManagerImpl.startup(RedundancyManagerImpl.java:283)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.initRedundancy(IgnitionGateway.java:697)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.lambda$initInternal$0(IgnitionGateway.java:635)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at com.inductiveautomation.ignition.common.execution.impl.BasicExecutionEngine$ThrowableCatchingRunnable.run(BasicExecutionEngine.java:518)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.util.concurrent.FutureTask.run(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at java.base/java.lang.Thread.run(Unknown Source)
INFO   | jvm 1    | 2020/06/02 15:38:59 | Caused by: java.lang.IllegalArgumentException: Cannot create PyString with non-byte value
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyString.<init>(PyString.java:57)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyString.<init>(PyString.java:70)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyString.<init>(PyString.java:74)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.Py.newString(Py.java:647)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyJavaType.init(PyJavaType.java:585)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.createType(PyType.java:1573)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.addFromClass(PyType.java:1508)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyType.fromClass(PyType.java:1609)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyObject.<init>(PyObject.java:93)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PySingleton.<init>(PySingleton.java:9)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.PyNotImplemented.<init>(PyNotImplemented.java:10)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	at org.python.core.Py.<clinit>(Py.java:94)
INFO   | jvm 1    | 2020/06/02 15:38:59 | 	... 38 common frames omitted

PGriffith · June 2, 2020, 4:18pm

What version of Ignition is this? This issue should have been resolved by an update to our bundled Jython.

Kevin.Herron · June 2, 2020, 4:23pm

It’s 8.0.12 according to the logs.

Kevin.Herron · June 2, 2020, 4:30pm

You should probably get a ticket going with support. Not sure what’s going on here. Call instead of email if it’s urgent.

taglias · August 8, 2020, 3:46pm

Yesterday I saw the same problem on 8.0.14. I uninstalled and reinstalled ignition and restored a backup, because I needed it immediately, I had no time to test. Any information on this problem?

nathan.dehoog · February 4, 2021, 11:45pm

Just had the same error occur in 8.0.16 on a Windows 10 Enterprise 2016 LSTB machine.

batuhan.parlakay · January 3, 2021, 11:00am

Ignition service is running but gateway is not starting. İgnition version 8.1.1 and sharing wrapper.log

STATUS | wrapperm | 2021/01/03 13:28:09 | Starting the Ignition service...
STATUS | wrapper  | 2021/01/03 13:28:12 | --> Wrapper Started as Service
STATUS | wrapper  | 2021/01/03 13:28:12 | Java Service Wrapper Standard Edition 64-bit 3.5.42
STATUS | wrapper  | 2021/01/03 13:28:12 |   Copyright (C) 1999-2020 Tanuki Software, Ltd. All Rights Reserved.
STATUS | wrapper  | 2021/01/03 13:28:12 |     http://wrapper.tanukisoftware.com
STATUS | wrapper  | 2021/01/03 13:28:12 |   Licensed to Inductive Automation for Inductive Automation
STATUS | wrapper  | 2021/01/03 13:28:12 | 
STATUS | wrapper  | 2021/01/03 13:28:13 | Launching a JVM...
INFO   | jvm 1    | 2021/01/03 13:28:13 | WrapperManager: Initializing...
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,150 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [data\\logback.xml] at [file:/C:/Program%20Files/Inductive%20Automation/Ignition/data/logback.xml]
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,231 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,233 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SysoutAppender]
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,238 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,256 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [com.inductiveautomation.logging.SQLiteAppender]
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,263 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DB]
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,786 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@42481d1b - Driver name=SQLite JDBC
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,786 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@42481d1b - Driver version=3.23.1
INFO   | jvm 1    | 2021/01/03 13:28:14 | 13:28:14,786 |-INFO in ch.qos.logback.core.db.DataSourceConnectionSource@42481d1b - supportsGetGeneratedKeys=true
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,430 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,435 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SysoutAsync]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,436 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SysoutAppender] to ch.qos.logback.classic.AsyncAppender[SysoutAsync]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,436 |-INFO in ch.qos.logback.classic.AsyncAppender[SysoutAsync] - Attaching appender named [SysoutAppender] to AsyncAppender.
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,439 |-INFO in ch.qos.logback.classic.AsyncAppender[SysoutAsync] - Setting discardingThreshold to 51
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,443 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [DBAsync]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [DB] to ch.qos.logback.classic.AsyncAppender[DBAsync]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Attaching appender named [DB] to AsyncAppender.
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.classic.AsyncAppender[DBAsync] - Setting discardingThreshold to 51
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,447 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SysoutAsync] to Logger[ROOT]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,448 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [DBAsync] to Logger[ROOT]
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,448 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,448 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@367e5204 - Registering current configuration as safe fallback point
INFO   | jvm 1    | 2021/01/03 13:28:15 | 13:28:15,490 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@698c4a36 - Propagating INFO level on Logger[ROOT] onto the JUL framework
INFO   | jvm 1    | 2021/01/03 13:28:15 | W [WebResourceManagerImpl        ] [10:28:15]: Unable to read "gateway.metroKeystoreAlias" gateway.xml property. Assuming metro keystore alias is: metro-key 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [o.e.j.u.log                   ] [10:28:15]: Logging initialized @2250ms to org.eclipse.jetty.util.log.Slf4jLog 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: Starting setup 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.forceSecureRedirect property is not set. Defaulting to false 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.includedCipherSuites property is not set. Defaulting to [] 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.excludedCipherSuites property is not set. Defaulting to [] 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.publicAddress.autoDetect property is not set. Defaulting to true 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.publicAddress.address property is not set. Defaulting to  
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.publicAddress.httpPort property is not set. Defaulting to  
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: gateway.publicAddress.httpsPort property is not set. Defaulting to  
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [g.WebServerManager            ] [10:28:15]: Setup complete 
INFO   | jvm 1    | 2021/01/03 13:28:15 | I [Commissioning                 ] [10:28:15]: Commissioning State updated from commıssıonıng to needs_commıssıonıng 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [g.WebServerManager            ] [10:28:16]: Starting up 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [C.SecureRandomProvider        ] [10:28:16]: Secure random seed generated in 0ms 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.Server                ] [10:28:16]: jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.7+10-LTS 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.session               ] [10:28:16]: DefaultSessionIdManager workerName=node0 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.session               ] [10:28:16]: No SessionScavenger set, using defaults 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.session               ] [10:28:16]: node0 Scavenging every 600000ms 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.h.ContextHandler      ] [10:28:16]: Started o.e.j.w.WebAppContext@4c85a985{Commissioning,/,file:///C:/Program%20Files/Inductive%20Automation/Ignition/webserver/webapps/commissioning/,AVAILABLE} 
STATUS | wrapperm | 2021/01/03 13:28:17 | Ignition service started.
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.AbstractConnector     ] [10:28:16]: Started ServerConnector@6f3a19ad{HTTP/1.1,[http/1.1]}{0.0.0.0:8088} 
INFO   | jvm 1    | 2021/01/03 13:28:16 | I [o.e.j.s.Server                ] [10:28:16]: Started @3013ms 
INFO   | jvm 1    | 2021/01/03 13:28:18 | WARNING: An illegal reflective access operation has occurred
INFO   | jvm 1    | 2021/01/03 13:28:18 | WARNING: Illegal reflective access by com.inductiveautomation.ignition.gateway.util.IgnitionSocketImplFactory (file:/C:/Program%20Files/Inductive%20Automation/Ignition/lib/core/gateway/gateway-8.1.1.jar) to constructor java.net.SocksSocketImpl()
INFO   | jvm 1    | 2021/01/03 13:28:18 | WARNING: Please consider reporting this to the maintainers of com.inductiveautomation.ignition.gateway.util.IgnitionSocketImplFactory
INFO   | jvm 1    | 2021/01/03 13:28:18 | WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
INFO   | jvm 1    | 2021/01/03 13:28:18 | WARNING: All illegal access operations will be denied in a future release
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.e.j.s.AbstractConnector     ] [10:28:55]: Started ServerConnector@1191eaae{SSL,[ssl, http/1.1]}{0.0.0.0:8060} 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.e.j.s.h.ContextHandler      ] [10:28:55]: Stopped o.e.j.w.WebAppContext@4c85a985{Commissioning,/,file:///C:/Program%20Files/Inductive%20Automation/Ignition/webserver/webapps/commissioning/,UNAVAILABLE} 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [Commissioning                 ] [10:28:55]: Commissioning State updated from needs_commıssıonıng to commıssıoned 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.Application             ] [10:28:55]: [WicketFilter] init: Wicket core library initializer 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=IBehaviorListener, method=public abstract void org.apache.wicket.behavior.IBehaviorListener.onRequest()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=IFormSubmitListener, method=public abstract void org.apache.wicket.markup.html.form.IFormSubmitListener.onFormSubmitted()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=ILinkListener, method=public abstract void org.apache.wicket.markup.html.link.ILinkListener.onLinkClicked()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=IOnChangeListener, method=public abstract void org.apache.wicket.markup.html.form.IOnChangeListener.onSelectionChanged()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=IRedirectListener, method=public abstract void org.apache.wicket.IRedirectListener.onRedirect()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.RequestListenerInterface] [10:28:55]: registered listener interface [RequestListenerInterface name=IResourceListener, method=public abstract void org.apache.wicket.IResourceListener.onResourceRequested()] 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.Application             ] [10:28:55]: [WicketFilter] init: Wicket extensions initializer 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [IgnitionGateway               ] [10:28:55]: Ignition[state=STOPPED] ContextState = STARTING 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.a.w.p.h.WebApplication      ] [10:28:55]: [WicketFilter] Started Wicket version 6.1.1 in DEPLOYMENT mode 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [IgnitionGateway               ] [10:28:55]: Starting Ignition 8.1.1 (b2020120808) 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [IgnitionGateway               ] [10:28:55]: Reloading JDBC classloader. 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [o.e.j.s.h.ContextHandler      ] [10:28:55]: Started c.i.c.MainWebAppContext@7e5dadb3{Ignition,/,file:///C:/Program%20Files/Inductive%20Automation/Ignition/webserver/webapps/main/,AVAILABLE} 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [R.S.S.InternalDb              ] [10:28:55]: Internal db synchronizer disk cache initialized. State id=null 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [R.StateMonitoring             ] [10:28:55]: Redundancy state changed: Role=Independent, Activity level=Active, Project state=Good, History level=Full 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [g.InternalDatabaseManager     ] [10:28:55]: Starting up... 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [P.InternalDatabase            ] [10:28:55]: Looking for existing internal database "config.idb"... 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [P.InternalDatabase            ] [10:28:55]: ... no files found, will create. 
INFO   | jvm 1    | 2021/01/03 13:28:55 | I [P.InternalDatabase            ] [10:28:55]: Creating new internal database "config.idb". 
INFO   | jvm 1    | 2021/01/03 13:28:56 | I [g.InternalDatabaseManager     ] [10:28:56]: Upgrading schema for module "ignition" 
INFO   | jvm 1    | 2021/01/03 13:28:56 | I [P.InternalDatabase            ] [10:28:56]: internal database "config.idb" started up successfully. 
INFO   | jvm 1    | 2021/01/03 13:28:56 | I [P.InternalDatabase            ] [10:28:56]: Registering autobackup task [owner=Internal DB Autobackup, name=internal database "config.idb"] 
INFO   | jvm 1    | 2021/01/03 13:28:56 | I [g.ModuleManager               ] [10:28:56]: Loading modules.... 
INFO   | jvm 1    | 2021/01/03 13:29:00 | I [A.Notification                ] [10:29:00]: Alert notification system created. 
INFO   | jvm 1    | 2021/01/03 13:29:03 | I [g.PersistentRecordSecurityLevelConfigService] [10:29:03]: Set up in 306 ms 
INFO   | jvm 1    | 2021/01/03 13:29:04 | I [g.InternalDatabaseManager     ] [10:29:04]: Upgrading schema for module "ignition" 
INFO   | jvm 1    | 2021/01/03 13:29:05 | I [g.PersistentRecordIdpAdapterConfigService] [10:29:05]: Successfully added new Identity Provider with name "default" from User Source Profile 
INFO   | jvm 1    | 2021/01/03 13:29:05 | I [g.PersistentRecordIdpAdapterConfigService] [10:29:05]: Set up in 1213 ms 
INFO   | jvm 1    | 2021/01/03 13:29:05 | I [g.InternalDatabaseManager     ] [10:29:05]: Upgrading schema for module "ignition" 
INFO   | jvm 1    | 2021/01/03 13:29:05 | I [g.PersistentRecordIdpAdapterMetricsService] [10:29:05]: Legacy User History Table not found. 
INFO   | jvm 1    | 2021/01/03 13:29:05 | I [g.PersistentRecordIdpAdapterMetricsService] [10:29:05]: Set up in 576 ms 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:05]: System properties does not exist in IDB. Creating default system properties... 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [g.InternalDatabaseManager     ] [10:29:06]: Upgrading schema for module "ignition" 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: Upgrading setting 'ConfigRoleName' with value 'Administrator' to 'ConfigPermissions' with value '{"type":"AnyOf","securityLevels":[{"name":"Authenticated","description":"Represents a user who has been authenticated by the system.","children":[{"name":"Roles","description":"Represents the roles that a user has.","children":[{"name":"Administrator","children":[]}]}]}]}' 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: Upgrading setting 'StatusPageRoleName' with value 'Administrator' to 'StatusPagePermissions' with value '{"type":"AnyOf","securityLevels":[{"name":"Authenticated","description":"Represents a user who has been authenticated by the system.","children":[{"name":"Roles","description":"Represents the roles that a user has.","children":[{"name":"Administrator","children":[]}]}]}]}' 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: Upgrading setting 'HomePageRoleName' with value '' to 'HomePagePermissions' with value '{"type":"AnyOf","securityLevels":[]}' 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: Upgrading setting 'DesignerRoleName' with value 'Administrator' to 'DesignerPermissions' with value '{"type":"AnyOf","securityLevels":[{"name":"Authenticated","description":"Represents a user who has been authenticated by the system.","children":[{"name":"Roles","description":"Represents the roles that a user has.","children":[{"name":"Administrator","children":[]}]}]}]}' 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: Upgrading setting 'CreateProjectRoleName' with value '' to 'CreateProjectPermissions' with value '{"type":"AnyOf","securityLevels":[]}' 
INFO   | jvm 1    | 2021/01/03 13:29:06 | I [IgnitionGateway               ] [10:29:06]: 1 rows affected 
INFO   | jvm 1    | 2021/01/03 13:29:07 | I [g.InternalDatabaseRememberedSubjects] [10:29:07]: Set up in 273 ms 
INFO   | jvm 1    | 2021/01/03 13:29:07 | I [g.OIDCProviderManager         ] [10:29:07]: Set up in 274 ms 
INFO   | jvm 1    | 2021/01/03 13:29:08 | I [g.InternalDatabaseManager     ] [10:29:07]: Upgrading schema for module "" 
INFO   | jvm 1    | 2021/01/03 13:29:08 | I [MetricHistoryManager          ] [10:29:08]: Creating new metrics database 
INFO   | jvm 1    | 2021/01/03 13:29:10 | W [g.LicenseManager              ] [10:29:10]: Could not read license from HASP key. HASP login() failed. Last error = 7. 
INFO   | jvm 1    | 2021/01/03 13:29:12 | I [g.InternalDatabaseManager     ] [10:29:11]: Upgrading schema for module "ignition" 
INFO   | jvm 1    | 2021/01/03 13:29:14 | I [t.m.provider                  ] [10:29:14]: Creating new tag store for provider 'System'. 
INFO   | jvm 1    | 2021/01/03 13:29:16 | I [t.s.internaljson              ] [10:29:16]: Redundancy version token not set for provider 'System', will initialize to system version. provider=System
INFO   | jvm 1    | 2021/01/03 13:29:17 | I [g.InternalDatabaseManager     ] [10:29:17]: Upgrading schema for module "" 
INFO   | jvm 1    | 2021/01/03 13:29:19 | I [m.M.Routing                   ] [10:29:19]: Setting max proxy route depth = 1 
INFO   | jvm 1    | 2021/01/03 13:29:19 | I [c.i.i.g.p.r.ProjectRecordConverter] [10:29:19]: Conversion finished. Elapsed time: 25 ms 
INFO   | jvm 1    | 2021/01/03 13:29:19 | E [C.BasicExecutionEngine        ] [10:29:19]: One-shot task com.inductiveautomation.ignition.gateway.IgnitionGateway$$Lambda$407/0x00000001004a2040@786bd703 threw uncaught exception. 
INFO   | jvm 1    | 2021/01/03 13:29:19 | java.lang.ExceptionInInitializerError: null
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.init(PyType.java:686)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.createType(PyType.java:1573)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.addFromClass(PyType.java:1508)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.fromClass(PyType.java:1609)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.fromClass(PyType.java:1587)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyStringMap.getLazyType(PyStringMap.java:68)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyStringMap.<init>(PyStringMap.java:48)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyStringMap.<init>(PyStringMap.java:44)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.expose.BaseTypeBuilder.getDict(BaseTypeBuilder.java:49)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.init(PyType.java:674)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.addBuilder(PyType.java:1498)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.<clinit>(PyType.java)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyObject.<init>(PyObject.java:93)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyBuiltinCallable.<init>(PyBuiltinCallable.java:22)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyBuiltinMethod.<init>(PyBuiltinMethod.java:16)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyBuiltinMethodNarrow.<init>(PyBuiltinMethodNarrow.java:23)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyDataDescr$getset_descriptor___get___exposer.<init>(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyDataDescr$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyDataDescr.<clinit>(PyDataDescr.java)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyBuiltinCallable$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyBuiltinCallable.<clinit>(PyBuiltinCallable.java)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyObject$PyExposer.<init>(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyObject.<clinit>(PyObject.java)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.common.script.ScriptManager.asynchInit(ScriptManager.java:205)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.common.script.ScriptManager.<init>(ScriptManager.java:116)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.createScriptManager(IgnitionGateway.java:1308)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.script.GatewayScriptManagerHolder.initializeBeforeModuleSetup(GatewayScriptManagerHolder.java:67)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.startupInternal(IgnitionGateway.java:1055)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.redundancy.RedundancyManagerImpl.startup(RedundancyManagerImpl.java:293)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.initRedundancy(IgnitionGateway.java:724)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.gateway.IgnitionGateway.lambda$initInternal$0(IgnitionGateway.java:658)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at com.inductiveautomation.ignition.common.execution.impl.BasicExecutionEngine$ThrowableCatchingRunnable.run(BasicExecutionEngine.java:518)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.util.concurrent.FutureTask.run(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at java.base/java.lang.Thread.run(Unknown Source)
INFO   | jvm 1    | 2021/01/03 13:29:19 | Caused by: java.lang.IllegalArgumentException: Cannot create PyString with non-byte value
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyString.<init>(PyString.java:57)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyString.<init>(PyString.java:70)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyString.<init>(PyString.java:74)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.Py.newString(Py.java:647)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyJavaType.init(PyJavaType.java:585)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.createType(PyType.java:1573)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.addFromClass(PyType.java:1508)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyType.fromClass(PyType.java:1609)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyObject.<init>(PyObject.java:93)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PySingleton.<init>(PySingleton.java:9)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.PyNotImplemented.<init>(PyNotImplemented.java:10)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	at org.python.core.Py.<clinit>(Py.java:94)
INFO   | jvm 1    | 2021/01/03 13:29:19 | 	... 38 common frames omitted

KathyApplebaum · January 4, 2021, 4:38pm

It’s failing to start the script manager for some reason. I can’t recall seeing this in 8.1.1, so you might want to get in touch with support and have them help you with this.

seyfullah.sakalli · January 6, 2021, 12:46pm

I am getting the same problem. Help please.

kcollins1 · January 6, 2021, 2:42pm

Posting more information about your runtime environment might help as well (in addition to the logs of course). There are many ways to run Ignition nowadays, and some information on those contexts can help reveal clues on why something might not be behaving itself. What OS are you running? If you’re running in a container environment, how are you launching it? Are there any other details about your installation that make it different or unique? Data helps!

PGriffith · January 6, 2021, 6:21pm

What locale are you using? Can you provide a .gwbk to support?

batuhan.parlakay · February 3, 2021, 7:20pm

I have a windows 8.1 64 bit operating system.It works smoothly in ignition 7.9.17 version.
But problems that 8.1.1 version not starting…My Java version is up to date.
But it still gets stuck on the page in the photo.

wrapper.log (24.3 KB)

batuhan.parlakay · February 3, 2021, 7:23pm

Gives an HTTP error 404 not found , after a long wait and refresh the page

PGriffith · February 3, 2021, 7:51pm

What locale are you using? Can you provide a .gwbk to support?

PGriffith · February 5, 2021, 12:54am

Clearly, this issue is happening intermittently to multiple users - but we’ve never been able to replicate in house. @nathan.dehoog, can you provide any more information about your system? Was this a brand new install, or were you restoring a .gwbk? Upgrading an existing version that was working? What locale is your system set to?

nathan.dehoog · February 5, 2021, 1:29am

Hi Paul, I have raised a support ticket for this already (#7733). I have included both the wrapper log and thread dump while the gateway was having issues.

The Ignition installation had been running for approximately 6 months without any issues.
It is running on an Advantech Embedded IPC which is running Windows 10 Enterprise 2016 LTSB. The machine did not reboot or power down as far as I can tell from the Windows Event Logs. It does not have internet access and it was therefore not installing Windows updates.

ruairi.daly · February 25, 2021, 9:18pm

I’ve seen the same issue several times in the last month on multiple Onlogic IPCs, windows 10, Ignition Edge 8.1.1… Seems to be caused when the IPC gets its power removed without proper Ignition/Windows shutdown first. Only fix I’ve found is a full Ignition reinstall. We’re trying to manage uncontrolled shutdowns buts its extremely difficult to do this in our application.