Topics

Web Server Exceptions

Dan Boudreau
 

With the latest commits, I’m seeing problems reported to the console when I access the JMRI Web Server.  These issues begin when I simply open my browser to the JMRI start page.  Using the operations conductor features completely fail, operation manifests seem to work, but with console errors.

 

Here’s the console when I first access the JMRI web page:

 

2019-10-23 11:54:52,771 util.Log4JUtil                        INFO  - ****** JMRI log ******* [main]

2019-10-23 11:54:52,782 util.Log4JUtil                        INFO  - This log is appended to file: C:\Users\dabou\JMRI\log\messages.log [main]

2019-10-23 11:54:52,783 util.Log4JUtil                        INFO  - This log is stored in file: C:\Users\dabou\JMRI\log\session.log [main]

2019-10-23 11:54:52,811 apps.Apps                             INFO  - PanelPro version 4.17.5ish+danielboudreau+20191023T1538Z starts under Java 1.8.0_221 on Windows 10 amd64 v10.0 at Wed Oct 23 11:54:52 EDT 2019 [main]

2019-10-23 11:54:53,074 apps.Apps                             INFO  - Starting with profile Demo.3eca54dc [main]

2019-10-23 11:54:53,362 node.NodeIdentity                     INFO  - Using jmri-gefnoc2JrhNiaaypz1OsrX-3eca54dc as the JMRI storage identity for profile id 3eca54dc [AWT-EventQueue-0]

2019-10-23 11:54:53,484 xml.AbstractSerialConnectionConfigXml INFO  - Starting to connect for "NCE" [main]

2019-10-23 11:54:53,528 simulator.SimulatorAdapter            INFO  - NCE Simulator Started [Nce Simulator]

2019-10-23 11:54:53,608 nce.NceConnectionStatus               INFO  - NCE EPROM revision = 6.2.1 [AWT-EventQueue-0]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path program: is C:\Program Files (x86)\JMRI4.17.4\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path preference: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path profile: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path settings: is C:\Users\dabou\JMRI\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path home: is C:\Users\dabou\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path scripts: is C:\Program Files (x86)\JMRI4.17.4\jython\ [main]

2019-10-23 11:54:55,773 PanelPro.PanelPro                     INFO  - Main initialization done [main]

2019-10-23 11:55:10,868 server.WebServer                      INFO  - Starting Web Server on port 12080 [WebServer]

2019-10-23 11:55:11,318 server.WebServer                      INFO  - Starting ZeroConfService _http._tcp.local for Web Server with properties {path=/, json=5.1.0} [WebServer]

2019-10-23 11:55:28,061 server.HttpChannel                    WARN  - /json/networkServices [WebServer-31]

java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at jmri.web.servlet.json.JsonServlet.init(JsonServlet.java:69)

              at javax.servlet.GenericServlet.init(GenericServlet.java:244)

              at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:637)

              at org.eclipse.jetty.servlet.ServletHolder.getServlet(ServletHolder.java:498)

              at org.eclipse.jetty.servlet.ServletHolder.ensureInstance(ServletHolder.java:785)

              at org.eclipse.jetty.servlet.ServletHolder.prepare(ServletHolder.java:770)

              at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:530)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)

              at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)

              at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)

              at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)

              at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)

              at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:219)

              at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)

              at org.eclipse.jetty.server.Server.handle(Server.java:561)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:334)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

2019-10-23 11:55:28,061 json.JsonServlet                      WARN  - Type rosterGroups unknown. [WebServer-34]

2019-10-23 11:55:28,285 json.JsonWebSocket                    ERROR - Unanticipated error Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession] [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:28,287 common.WebSocketSession               WARN  -  [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:29,035 json.JsonServlet                      WARN  - Type power unknown. [WebServer-37]

 

Randall Wood
 

Try a clean and complete rebuild.

Randall

On Oct 23, 2019, at 12:03, Dan Boudreau <daboudreau@...> wrote:



With the latest commits, I’m seeing problems reported to the console when I access the JMRI Web Server.  These issues begin when I simply open my browser to the JMRI start page.  Using the operations conductor features completely fail, operation manifests seem to work, but with console errors.

 

Here’s the console when I first access the JMRI web page:

 

2019-10-23 11:54:52,771 util.Log4JUtil                        INFO  - ****** JMRI log ******* [main]

2019-10-23 11:54:52,782 util.Log4JUtil                        INFO  - This log is appended to file: C:\Users\dabou\JMRI\log\messages.log [main]

2019-10-23 11:54:52,783 util.Log4JUtil                        INFO  - This log is stored in file: C:\Users\dabou\JMRI\log\session.log [main]

2019-10-23 11:54:52,811 apps.Apps                             INFO  - PanelPro version 4.17.5ish+danielboudreau+20191023T1538Z starts under Java 1.8.0_221 on Windows 10 amd64 v10.0 at Wed Oct 23 11:54:52 EDT 2019 [main]

2019-10-23 11:54:53,074 apps.Apps                             INFO  - Starting with profile Demo.3eca54dc [main]

2019-10-23 11:54:53,362 node.NodeIdentity                     INFO  - Using jmri-gefnoc2JrhNiaaypz1OsrX-3eca54dc as the JMRI storage identity for profile id 3eca54dc [AWT-EventQueue-0]

2019-10-23 11:54:53,484 xml.AbstractSerialConnectionConfigXml INFO  - Starting to connect for "NCE" [main]

2019-10-23 11:54:53,528 simulator.SimulatorAdapter            INFO  - NCE Simulator Started [Nce Simulator]

2019-10-23 11:54:53,608 nce.NceConnectionStatus               INFO  - NCE EPROM revision = 6.2.1 [AWT-EventQueue-0]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path program: is C:\Program Files (x86)\JMRI4.17.4\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path preference: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path profile: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path settings: is C:\Users\dabou\JMRI\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path home: is C:\Users\dabou\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path scripts: is C:\Program Files (x86)\JMRI4.17.4\jython\ [main]

2019-10-23 11:54:55,773 PanelPro.PanelPro                     INFO  - Main initialization done [main]

2019-10-23 11:55:10,868 server.WebServer                      INFO  - Starting Web Server on port 12080 [WebServer]

2019-10-23 11:55:11,318 server.WebServer                      INFO  - Starting ZeroConfService _http._tcp.local for Web Server with properties {path=/, json=5.1.0} [WebServer]

2019-10-23 11:55:28,061 server.HttpChannel                    WARN  - /json/networkServices [WebServer-31]

java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at jmri.web.servlet.json.JsonServlet.init(JsonServlet.java:69)

              at javax.servlet.GenericServlet.init(GenericServlet.java:244)

              at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:637)

              at org.eclipse.jetty.servlet.ServletHolder.getServlet(ServletHolder.java:498)

              at org.eclipse.jetty.servlet.ServletHolder.ensureInstance(ServletHolder.java:785)

              at org.eclipse.jetty.servlet.ServletHolder.prepare(ServletHolder.java:770)

              at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:530)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)

              at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)

              at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)

              at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)

              at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)

              at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:219)

              at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)

              at org.eclipse.jetty.server.Server.handle(Server.java:561)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:334)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

2019-10-23 11:55:28,061 json.JsonServlet                      WARN  - Type rosterGroups unknown. [WebServer-34]

2019-10-23 11:55:28,285 json.JsonWebSocket                    ERROR - Unanticipated error Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession] [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:28,287 common.WebSocketSession               WARN  -  [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:29,035 json.JsonServlet                      WARN  - Type power unknown. [WebServer-37]

 

Dan Boudreau
 

Needed to perform a “realclean” to get the errors to disappear, thanks.

 

Dan

 


From: jmri@jmri-developers.groups.io <jmri@jmri-developers.groups.io> on behalf of Randall Wood via Groups.Io <rhwood@...>
Sent: Wednesday, October 23, 2019 3:01:40 PM
To: jmri@jmri-developers.groups.io <jmri@jmri-developers.groups.io>
Subject: Re: [jmri-developers] Web Server Exceptions
 
Try a clean and complete rebuild.

Randall

On Oct 23, 2019, at 12:03, Dan Boudreau <daboudreau@...> wrote:



With the latest commits, I’m seeing problems reported to the console when I access the JMRI Web Server.  These issues begin when I simply open my browser to the JMRI start page.  Using the operations conductor features completely fail, operation manifests seem to work, but with console errors.

 

Here’s the console when I first access the JMRI web page:

 

2019-10-23 11:54:52,771 util.Log4JUtil                        INFO  - ****** JMRI log ******* [main]

2019-10-23 11:54:52,782 util.Log4JUtil                        INFO  - This log is appended to file: C:\Users\dabou\JMRI\log\messages.log [main]

2019-10-23 11:54:52,783 util.Log4JUtil                        INFO  - This log is stored in file: C:\Users\dabou\JMRI\log\session.log [main]

2019-10-23 11:54:52,811 apps.Apps                             INFO  - PanelPro version 4.17.5ish+danielboudreau+20191023T1538Z starts under Java 1.8.0_221 on Windows 10 amd64 v10.0 at Wed Oct 23 11:54:52 EDT 2019 [main]

2019-10-23 11:54:53,074 apps.Apps                             INFO  - Starting with profile Demo.3eca54dc [main]

2019-10-23 11:54:53,362 node.NodeIdentity                     INFO  - Using jmri-gefnoc2JrhNiaaypz1OsrX-3eca54dc as the JMRI storage identity for profile id 3eca54dc [AWT-EventQueue-0]

2019-10-23 11:54:53,484 xml.AbstractSerialConnectionConfigXml INFO  - Starting to connect for "NCE" [main]

2019-10-23 11:54:53,528 simulator.SimulatorAdapter            INFO  - NCE Simulator Started [Nce Simulator]

2019-10-23 11:54:53,608 nce.NceConnectionStatus               INFO  - NCE EPROM revision = 6.2.1 [AWT-EventQueue-0]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path program: is C:\Program Files (x86)\JMRI4.17.4\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path preference: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,319 util.FileUtilSupport                  INFO  - File path profile: is C:\Users\dabou\JMRI\Demo\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path settings: is C:\Users\dabou\JMRI\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path home: is C:\Users\dabou\ [main]

2019-10-23 11:54:55,320 util.FileUtilSupport                  INFO  - File path scripts: is C:\Program Files (x86)\JMRI4.17.4\jython\ [main]

2019-10-23 11:54:55,773 PanelPro.PanelPro                     INFO  - Main initialization done [main]

2019-10-23 11:55:10,868 server.WebServer                      INFO  - Starting Web Server on port 12080 [WebServer]

2019-10-23 11:55:11,318 server.WebServer                      INFO  - Starting ZeroConfService _http._tcp.local for Web Server with properties {path=/, json=5.1.0} [WebServer]

2019-10-23 11:55:28,061 server.HttpChannel                    WARN  - /json/networkServices [WebServer-31]

java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at jmri.web.servlet.json.JsonServlet.init(JsonServlet.java:69)

              at javax.servlet.GenericServlet.init(GenericServlet.java:244)

              at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:637)

              at org.eclipse.jetty.servlet.ServletHolder.getServlet(ServletHolder.java:498)

              at org.eclipse.jetty.servlet.ServletHolder.ensureInstance(ServletHolder.java:785)

              at org.eclipse.jetty.servlet.ServletHolder.prepare(ServletHolder.java:770)

              at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:530)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)

              at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)

              at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)

              at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)

              at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)

              at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)

              at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:219)

              at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)

              at org.eclipse.jetty.server.Server.handle(Server.java:561)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:334)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

2019-10-23 11:55:28,061 json.JsonServlet                      WARN  - Type rosterGroups unknown. [WebServer-34]

2019-10-23 11:55:28,285 json.JsonWebSocket                    ERROR - Unanticipated error Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession] [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:28,287 common.WebSocketSession               WARN  -  [WebServer-32]

java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)

              at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)

              at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)

              at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)

              at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)

              at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)

              at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)

              at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)

              at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)

              at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)

              at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)

              at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)

              at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)

              at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)

              at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)

              at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)

              at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)

              at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)

              at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)

              at java.lang.Thread.run(Unknown Source)

Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found

              at java.util.ServiceLoader.fail(Unknown Source)

              at java.util.ServiceLoader.access$300(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)

              at java.util.ServiceLoader$LazyIterator.next(Unknown Source)

              at java.util.ServiceLoader$1.next(Unknown Source)

              at java.lang.Iterable.forEach(Unknown Source)

              at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)

              at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)

              at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

              at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

              at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

              at java.lang.reflect.Method.invoke(Unknown Source)

              at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)

              ... 21 more

2019-10-23 11:55:29,035 json.JsonServlet                      WARN  - Type power unknown. [WebServer-37]

 

Randall Wood
 

This kind of failure will appear after updating a working copy when the case of a package or class in Java is changed and Ant’s “smart" compilation (ant tries to tell if the source changed) fails on a case-insensitive file system. The hint in this case was the “java.util.ServiceConfigurationError: SomeClass: Provider SomeOtherClass not found” message.

On 23 Oct 2019, at 16:30, Dan Boudreau <daboudreau@...> wrote:

Needed to perform a “realclean” to get the errors to disappear, thanks.

Dan

From: jmri@jmri-developers.groups.io <jmri@jmri-developers.groups.io> on behalf of Randall Wood via Groups.Io <rhwood=mac.com@groups.io>
Sent: Wednesday, October 23, 2019 3:01:40 PM
To: jmri@jmri-developers.groups.io <jmri@jmri-developers.groups.io>
Subject: Re: [jmri-developers] Web Server Exceptions

Try a clean and complete rebuild.

Randall

On Oct 23, 2019, at 12:03, Dan Boudreau <daboudreau@...> wrote:


With the latest commits, I’m seeing problems reported to the console when I access the JMRI Web Server. These issues begin when I simply open my browser to the JMRI start page. Using the operations conductor features completely fail, operation manifests seem to work, but with console errors.

Here’s the console when I first access the JMRI web page:

2019-10-23 11:54:52,771 util.Log4JUtil INFO - ****** JMRI log ******* [main]
2019-10-23 11:54:52,782 util.Log4JUtil INFO - This log is appended to file: C:\Users\dabou\JMRI\log\messages.log [main]
2019-10-23 11:54:52,783 util.Log4JUtil INFO - This log is stored in file: C:\Users\dabou\JMRI\log\session.log [main]
2019-10-23 11:54:52,811 apps.Apps INFO - PanelPro version 4.17.5ish+danielboudreau+20191023T1538Z starts under Java 1.8.0_221 on Windows 10 amd64 v10.0 at Wed Oct 23 11:54:52 EDT 2019 [main]
2019-10-23 11:54:53,074 apps.Apps INFO - Starting with profile Demo.3eca54dc [main]
2019-10-23 11:54:53,362 node.NodeIdentity INFO - Using jmri-gefnoc2JrhNiaaypz1OsrX-3eca54dc as the JMRI storage identity for profile id 3eca54dc [AWT-EventQueue-0]
2019-10-23 11:54:53,484 xml.AbstractSerialConnectionConfigXml INFO - Starting to connect for "NCE" [main]
2019-10-23 11:54:53,528 simulator.SimulatorAdapter INFO - NCE Simulator Started [Nce Simulator]
2019-10-23 11:54:53,608 nce.NceConnectionStatus INFO - NCE EPROM revision = 6.2.1 [AWT-EventQueue-0]
2019-10-23 11:54:55,319 util.FileUtilSupport INFO - File path program: is C:\Program Files (x86)\JMRI4.17.4\ [main]
2019-10-23 11:54:55,319 util.FileUtilSupport INFO - File path preference: is C:\Users\dabou\JMRI\Demo\ [main]
2019-10-23 11:54:55,319 util.FileUtilSupport INFO - File path profile: is C:\Users\dabou\JMRI\Demo\ [main]
2019-10-23 11:54:55,320 util.FileUtilSupport INFO - File path settings: is C:\Users\dabou\JMRI\ [main]
2019-10-23 11:54:55,320 util.FileUtilSupport INFO - File path home: is C:\Users\dabou\ [main]
2019-10-23 11:54:55,320 util.FileUtilSupport INFO - File path scripts: is C:\Program Files (x86)\JMRI4.17.4\jython\ [main]
2019-10-23 11:54:55,773 PanelPro.PanelPro INFO - Main initialization done [main]
2019-10-23 11:55:10,868 server.WebServer INFO - Starting Web Server on port 12080 [WebServer]
2019-10-23 11:55:11,318 server.WebServer INFO - Starting ZeroConfService _http._tcp.local for Web Server with properties {path=/, json=5.1.0} [WebServer]
2019-10-23 11:55:28,061 server.HttpChannel WARN - /json/networkServices [WebServer-31]
java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found
at java.util.ServiceLoader.fail(Unknown Source)
at java.util.ServiceLoader.access$300(Unknown Source)
at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)
at java.util.ServiceLoader$LazyIterator.next(Unknown Source)
at java.util.ServiceLoader$1.next(Unknown Source)
at jmri.web.servlet.json.JsonServlet.init(JsonServlet.java:69)
at javax.servlet.GenericServlet.init(GenericServlet.java:244)
at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:637)
at org.eclipse.jetty.servlet.ServletHolder.getServlet(ServletHolder.java:498)
at org.eclipse.jetty.servlet.ServletHolder.ensureInstance(ServletHolder.java:785)
at org.eclipse.jetty.servlet.ServletHolder.prepare(ServletHolder.java:770)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:530)
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:219)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at org.eclipse.jetty.server.Server.handle(Server.java:561)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:334)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)
at java.lang.Thread.run(Unknown Source)
2019-10-23 11:55:28,061 json.JsonServlet WARN - Type rosterGroups unknown. [WebServer-34]
2019-10-23 11:55:28,285 json.JsonWebSocket ERROR - Unanticipated error Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession] [WebServer-32]
java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)
at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)
at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)
at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)
at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)
at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)
at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)
at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)
at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)
at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)
at java.lang.Thread.run(Unknown Source)
Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found
at java.util.ServiceLoader.fail(Unknown Source)
at java.util.ServiceLoader.access$300(Unknown Source)
at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)
at java.util.ServiceLoader$LazyIterator.next(Unknown Source)
at java.util.ServiceLoader$1.next(Unknown Source)
at java.lang.Iterable.forEach(Unknown Source)
at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)
at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)
... 21 more
2019-10-23 11:55:28,287 common.WebSocketSession WARN - [WebServer-32]
java.lang.RuntimeException: Cannot call method public void jmri.server.json.JsonWebSocket#onOpen(org.eclipse.jetty.websocket.api.Session) with args: [org.eclipse.jetty.websocket.common.WebSocketSession]
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.unwrapRuntimeException(CallableMethod.java:94)
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:76)
at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onConnect(JettyAnnotatedEventDriver.java:148)
at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.openSession(AbstractEventDriver.java:233)
at org.eclipse.jetty.websocket.common.WebSocketSession.open(WebSocketSession.java:504)
at org.eclipse.jetty.websocket.common.WebSocketSession.onOpened(WebSocketSession.java:433)
at org.eclipse.jetty.io.AbstractConnection.onOpen(AbstractConnection.java:200)
at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onOpen(AbstractWebSocketConnection.java:566)
at org.eclipse.jetty.io.AbstractEndPoint.upgrade(AbstractEndPoint.java:432)
at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:376)
at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:651)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:459)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:104)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:243)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:679)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:597)
at java.lang.Thread.run(Unknown Source)
Caused by: java.util.ServiceConfigurationError: jmri.spi.JsonServiceFactory: Provider jmri.server.json.idTag.JsonIdTagServiceFactory not found
at java.util.ServiceLoader.fail(Unknown Source)
at java.util.ServiceLoader.access$300(Unknown Source)
at java.util.ServiceLoader$LazyIterator.nextService(Unknown Source)
at java.util.ServiceLoader$LazyIterator.next(Unknown Source)
at java.util.ServiceLoader$1.next(Unknown Source)
at java.lang.Iterable.forEach(Unknown Source)
at jmri.server.json.JsonClientHandler.<init>(JsonClientHandler.java:49)
at jmri.server.json.JsonWebSocket.onOpen(JsonWebSocket.java:37)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71)
... 21 more
2019-10-23 11:55:29,035 json.JsonServlet WARN - Type power unknown. [WebServer-37]