Chromecast Binding Log Errors

meingraham · September 26, 2018, 7:51pm

I’m holding off on taking the plunge on the full openHAB 2.4.0-SNAPSHOT. I’m probably too conservative, but I’m a little reticent to try anything other than the “released” openHAB. I’m OK with some baby steps, e.g., one binding at a time. Can I still just “upgrade” my Chromecast binding to this latest update on top of my 2.3.0 openHAB by downloading the latest Chromecast binding JAR? Can you make it available in the same download location as before?

Regards.

Mike

wborn · September 26, 2018, 7:56pm

It should still work and you can download the same JAR that is used with the latest openHAB 2.4.0-SNAPSHOT downloads from:

https://openhab.jfrog.io/openhab/online-repo-snapshot/2.4/org/openhab/binding/org.openhab.binding.chromecast/2.4.0-SNAPSHOT/org.openhab.binding.chromecast-2.4.0-SNAPSHOT.jar

That JAR was build by the openHAB Jenkins build server today.

meingraham · September 26, 2018, 9:06pm

OK, new binding deployed. Getting repeated BaseThingHandler warnings. These appear to be generated roughly every 13 seconds.

2018-09-26 16:52:40.230 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:52:40.248 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:52:40.257 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:52:40.268 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:audiogroup:xxx' to inbox.
2018-09-26 16:52:40.345 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:52:40.363 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:audio:xxx' to inbox.
2018-09-26 16:52:40.375 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:audiogroup:xxx' to inbox.
2018-09-26 16:52:40.386 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:52:40.400 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'chromecast:chromecast:xxx' to inbox.
2018-09-26 16:54:21.968 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:54:33.012 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:54:46.128 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:54:59.248 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:55:12.368 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:55:25.557 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:55:38.688 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:55:51.809 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:56:04.928 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:56:18.048 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:56:31.173 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:56:44.288 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:56:58.048 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:57:11.168 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:57:24.287 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:57:37.408 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:57:50.528 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:58:03.088 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:58:16.208 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:58:29.328 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:58:42.448 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:58:55.568 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:59:08.128 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.
2018-09-26 16:59:21.248 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler ChromecastHandler tried updating the thing status although the handler was already disposed.

wborn · September 26, 2018, 11:14pm

I created a fix for those in 2.4.0-SNAPSHOT after seeing Handler disposed?. Haven’t seen them myself thereafter. Restarting openHAB should normally solve it.

meingraham · September 26, 2018, 11:22pm

In the openHAB 2.4.0-SNAPSHOT? In other words, the fix in in some other part of the openHAB package… and not in the Chromecast 2.4.0-SNAPSHOT itself; yes?

I ask because I retrieved the Chromecast binding you updated today, I stopped OH2, deployed the new binding, and restarted OH2. This is when the warnings began.

In order to resolve this, I should go ahead and bite the bullet and update openHAB to 2.4.0-SNAPSHOT; yes?

Regards.

Mike

wborn · September 27, 2018, 7:27am

It was fixed in the binding itself. So either the 2.3.0 binding was running again or the issue is not fully solved.

Regarding the Chromecast binding it will not fix anything that’s not already in the JAR.

If you like to help out testing and/or SNAPSHOT builds fix your major issues or bring features you want to use now it may be worth it.

You could use also a more stable 2.4.0 milestone build instead. See:

meingraham · September 27, 2018, 12:53pm

Wouter,

I stopped openHAB and then started it again a few seconds later (to avoid the Jetty error 503 problem).

This time the Chromecast Things were not added (I suppose because they’d been added the first time this new binding started up). This time I am not getting the Handler already disposed warnings either. Maybe it only occurs the very first time? Perhaps a clue in finding what caused the issue?

It does appears that I have the latest Chromecast binding JAR.

/srv/openhab2-addons# jar -tvf *.jar
  1226 Wed Sep 26 19:42:52 EDT 2018 META-INF/MANIFEST.MF
     0 Wed Sep 26 19:42:52 EDT 2018 META-INF/
     0 Wed Sep 26 19:42:50 EDT 2018 OSGI-INF/
     0 Wed Sep 26 19:42:50 EDT 2018 org/
     0 Wed Sep 26 19:42:50 EDT 2018 org/openhab/
     0 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/
     0 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/
     0 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/
     0 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/handler/
     0 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/
     0 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/binding/
     0 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/thing/
     0 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/config/
     0 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/i18n/
     0 Wed Sep 26 19:34:44 EDT 2018 lib/
     0 Wed Sep 26 19:42:52 EDT 2018 META-INF/maven/
     0 Wed Sep 26 19:42:52 EDT 2018 META-INF/maven/org.openhab.binding/
     0 Wed Sep 26 19:42:52 EDT 2018 META-INF/maven/org.openhab.binding/org.openhab.binding.chromecast/
   877 Wed Sep 26 19:42:50 EDT 2018 OSGI-INF/org.openhab.binding.chromecast.internal.ChromecastHandlerFactory.xml
  1331 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/config/config.xml
   469 Wed Sep 26 19:42:50 EDT 2018 OSGI-INF/org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant.xml
  1580 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/i18n/chromecast_de.properties
  4302 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastDiscoveryParticipant.class
596435 Wed Sep 26 19:34:44 EDT 2018 lib/protobuf-java-2.6.0.jar
 13154 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastStatusUpdater.class
131909 Wed Sep 26 19:34:44 EDT 2018 lib/api-v2-0.10.4.jar
  5575 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastHandlerFactory.class
765648 Wed Sep 26 19:34:44 EDT 2018 lib/jackson-mapper-asl-1.9.2.jar
  8790 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastCommander.class
   563 Wed Sep 26 19:34:44 EDT 2018 META-INF/maven/org.openhab.binding/org.openhab.binding.chromecast/pom.xml
   144 Wed Sep 26 19:42:52 EDT 2018 META-INF/maven/org.openhab.binding/org.openhab.binding.chromecast/pom.properties
  9228 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/handler/ChromecastHandler.class
  1198 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/handler/ChromecastHandler$Coordinator$1.class
228286 Wed Sep 26 19:34:44 EDT 2018 lib/jackson-core-asl-1.9.2.jar
  7059 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/handler/ChromecastHandler$Coordinator.class
  2942 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastAudioSink.class
  3316 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastBindingConstants.class
  3807 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastEventReceiver.class
  2608 Wed Sep 26 19:42:50 EDT 2018 org/openhab/binding/chromecast/internal/ChromecastScheduler.class
  2169 Wed Sep 26 19:34:44 EDT 2018 about.html
 12642 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/thing/thing-types.xml
   762 Wed Sep 26 19:34:44 EDT 2018 ESH-INF/binding/binding.xml

Cheers!

Mike

P.S.

“more stable” - yes, I might indeed give this a go

meingraham · September 29, 2018, 6:46am

@wborn Wouter,

I updated to the latest openHAB SNAPSHOT (2.4.0~20180929001318-1 (Build #1375)) using openhabian-config. Doing so cleared my Karaf log setting for the litvak library log level back to WARN. The library is still generating warnings:

2018-09-29 01:14:03.350 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[188:org.openhab.binding.chromecast:2.4.0.201809261735]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[188:org.openhab.binding.chromecast:2.4.0.201809261735]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [188:org.openhab.binding.chromecast:2.4.0.201809261735]
2018-09-29 01:14:03.359 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2018-09-29 01:17:40.515 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[188:org.openhab.binding.chromecast:2.4.0.201809261735]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[188:org.openhab.binding.chromecast:2.4.0.201809261735]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [188:org.openhab.binding.chromecast:2.4.0.201809261735]
2018-09-29 01:17:40.523 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message

These may only be debug messages and normal, but I wanted to include them for you to be aware. The Chromecast log is full of these (as before):

29-Sep-2018 02:41:12.747 [DEBUG] [inding.chromecast.internal.ChromecastEventReceiver] - Received an 'UNKNOWN' event (class=class org.codehaus.jackson.JsonNode)
29-Sep-2018 02:41:12.888 [DEBUG] [ab.binding.chromecast.internal.ChromecastCommander] - Failed to request media status with a running app: Waiting for response timed out
29-Sep-2018 02:41:12.928 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Scheduling connection
29-Sep-2018 02:41:12.930 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Canceling connection
29-Sep-2018 02:41:26.609 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Scheduling connection
29-Sep-2018 02:41:26.610 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Canceling connection
29-Sep-2018 02:41:39.728 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Scheduling connection
29-Sep-2018 02:41:39.730 [DEBUG] [ab.binding.chromecast.internal.ChromecastScheduler] - Canceling connection
29-Sep-2018 02:41:44.625 [DEBUG] [inding.chromecast.internal.ChromecastEventReceiver] - Received an 'UNKNOWN' event (class=class org.codehaus.jackson.JsonNode)

wborn · September 29, 2018, 8:13am

Yes that is to be expected because issue #3770 is still open which needs to be fixed.

meingraham · September 29, 2018, 12:16pm

@wborn

OK. I’ll set the logging level back to ERROR to avoid that logging.

BTW, the “handler” issue did not occur after this “complete” openHAB update.

Should I set the Chromecast logging back to WARN or ERROR now instead of DEBUG? Have you all the information you want?

Thanks.

Mike

wborn · September 29, 2018, 12:39pm

I would set it to the default log level which is: INFO

Yes I have more than enough info! I also still see the exceptions on my own setup.

StefanH · October 28, 2018, 9:41am

EDIT: I answer myself

Locate the URL of the jar file
Find the ID of the binding you want to update in openHAB console using: bundle:list -s |grep YOURBINDING
Run: bundle:update ID BINDINGURL

hey Michael,

could you give me a hint and explain how to just add this new binding to OH 2.3 rather than upgrading OH to the lastest snapshot?

Stefan

meingraham · October 28, 2018, 12:09pm

Glad you figured it out…

Cheers!

Mike

Andrew_Pawelski · December 3, 2018, 8:41pm

Im on 2.4 Milestone and still getting HEAPS of errors:

[su.litvak.chromecast.api.v2.Channel ] - Error while reading
java.net.SocketException: Connection reset

am going to try the ping workaround

meingraham · December 3, 2018, 8:56pm

Would you mind letting me know if they go away?. I have pinging set up and have been debating whether that’s now just network traffic overhead. If you still have the errors after adding pinging, then I’d like to get rid of pinging configuration.

I changed the logging level for the litvak library to get rid of most, if not all of these. I can’t remember what I changed specifically. Here are the lines from my /srv/openhab2-userdata/etc/org.ops4j.pax.logging.cfg

log4j2.logger.org_openhab_library_litvak_chromecast.name = su.litvak.chromecast.api.v2
log4j2.logger.org_openhab_library_litvak_chromecast.level = ERROR
log4j2.logger.su_litvak_chromecast_api_v2.level = ERROR
log4j2.logger.su_litvak_chromecast_api_v2.name = su.litvak.chromecast.api.v2

Mike

Andrew_Pawelski · December 4, 2018, 12:59pm

@meingraham unfortunately not - my logs are still over flooded

2018-12-04 23:10:09.805 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.
org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException
	at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [170:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [171:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.Server.handle(Server.java:531) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]
	at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: org.eclipse.jetty.io.EofException
	at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:286) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:323) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:380) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:798) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:538) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:790) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:846) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:240) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:216) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:515) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.glassfish.jersey.servlet.internal.ResponseWriter$NonCloseableOutputStreamWrapper.write(ResponseWriter.java:325) ~[?:?]
	at org.glassfish.jersey.message.internal.CommittingOutputStream.write(CommittingOutputStream.java:229) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$UnCloseableOutputStream.write(WriterInterceptorExecutor.java:299) ~[?:?]
	at org.glassfish.jersey.message.internal.ReaderWriter.writeTo(ReaderWriter.java:116) ~[?:?]
	at org.glassfish.jersey.message.internal.AbstractMessageReaderWriterProvider.writeTo(AbstractMessageReaderWriterProvider.java:79) ~[?:?]
	at org.glassfish.jersey.message.internal.InputStreamProvider.writeTo(InputStreamProvider.java:105) ~[?:?]
	at org.glassfish.jersey.message.internal.InputStreamProvider.writeTo(InputStreamProvider.java:60) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.invokeWriteTo(WriterInterceptorExecutor.java:265) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.aroundWriteTo(WriterInterceptorExecutor.java:250) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
	at org.glassfish.jersey.server.internal.JsonWithPaddingInterceptor.aroundWriteTo(JsonWithPaddingInterceptor.java:106) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
	at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:86) ~[?:?]
	... 53 more
Caused by: java.io.IOException: Broken pipe
	at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:?]
	at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:?]
	at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:?]
	at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:?]
	at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:?]
	at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:264) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:323) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:380) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:798) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:538) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:790) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:846) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:240) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:216) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:515) ~[84:org.eclipse.jetty.server:9.4.11.v20180605]
	at org.glassfish.jersey.servlet.internal.ResponseWriter$NonCloseableOutputStreamWrapper.write(ResponseWriter.java:325) ~[?:?]
	at org.glassfish.jersey.message.internal.CommittingOutputStream.write(CommittingOutputStream.java:229) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$UnCloseableOutputStream.write(WriterInterceptorExecutor.java:299) ~[?:?]
	at org.glassfish.jersey.message.internal.ReaderWriter.writeTo(ReaderWriter.java:116) ~[?:?]
	at org.glassfish.jersey.message.internal.AbstractMessageReaderWriterProvider.writeTo(AbstractMessageReaderWriterProvider.java:79) ~[?:?]
	at org.glassfish.jersey.message.internal.InputStreamProvider.writeTo(InputStreamProvider.java:105) ~[?:?]
	at org.glassfish.jersey.message.internal.InputStreamProvider.writeTo(InputStreamProvider.java:60) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.invokeWriteTo(WriterInterceptorExecutor.java:265) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor$TerminalWriterInterceptor.aroundWriteTo(WriterInterceptorExecutor.java:250) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
	at org.glassfish.jersey.server.internal.JsonWithPaddingInterceptor.aroundWriteTo(JsonWithPaddingInterceptor.java:106) ~[?:?]
	at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[?:?]
	at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:86) ~[?:?]
	... 53 more

meingraham · December 4, 2018, 1:43pm

What was occurring when you received those log entries? I didn’t see a reference to either the litvak library nor chromecast in the text. I did see “output stream” referenced, but not Chromecast specifically. Are you certain that this is related to a Chromecast action?

Andrew_Pawelski · December 4, 2018, 1:49pm

Well possibly not I think the floods stop when the binding is uninstalled though - how can I tell?

Thanks
Andrew

meingraham · December 4, 2018, 6:23pm

@wborn - any thoughts?

Andrew - you could set up a custom logger for the Chromecast binding and set up verbose logging (e.g., TRACE or DEBUG). Perhaps that would identify what it was attempting when the log entries you are seeing get generated.

Mike

wborn · December 4, 2018, 6:31pm

Many people report the same issue if you use the search button. There’s also an issue for it: