Chromecast binding errors on starting 2.5.0 snapshot

scottk · December 26, 2018, 2:13pm

System details:

H/W Platform: RPi 3B
OS: Current OpenHABian
OpenHAB: 2.5.0-SNAPSHOT Build #1479
Chromecast binding installed via /etc/openhab2/services/addons.cfg

I have been running some of the recent 2.5.0 snapshot builds, most recently #1475 and as of this morning, #1479. With both of these snapshots, I am seeing numerous chromecast binding errors on starting OH:

2018-12-26 06:40:54.635 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastHandlerFactory(270)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
2018-12-26 06:40:54.677 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:40:54.677 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastHandlerFactory(270)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
2018-12-26 06:40:54.975 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:40:54.967 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastHandlerFactory(270)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
2018-12-26 06:40:55.990 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:40:56.009 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastHandlerFactory(270)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
2018-12-26 06:40:56.155 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:40:56.162 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant(271)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant
2018-12-26 06:40:56.296 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:40:56.305 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant(271)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant
2018-12-26 06:40:56.453 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)BundleComponentActivator : Unexpected failure enabling component holder org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant
2018-12-26 06:41:21.671 [ERROR][eduler_Worker-2][org.quartz.core.JobRunShell         ] - Job DEFAULT.2018-12-26T06:40:57.700-07:00: Proxy for org.eclipse.xtext.xbase.lib.Procedures$Procedure0: [ | {
2018-12-26 06:41:21.764 [ERROR][eduler_Worker-2][org.quartz.core.ErrorLogger         ] - Job (DEFAULT.2018-12-26T06:40:57.700-07:00: Proxy for org.eclipse.xtext.xbase.lib.Procedures$Procedure0: [ | {
2018-12-26 06:41:23.951 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastHandlerFactory(270)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
2018-12-26 06:41:23.958 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast
2018-12-26 06:41:23.959 [ERROR][17-1084d892a4b9][org.openhab.binding.chromecast      ] - bundle org.openhab.binding.chromecast:2.5.0.201812250308 (253)[org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant(271)] : Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastDiscoveryParticipant
2018-12-26 06:41:24.016 [ERROR][blisher@1341870][org.openhab.binding.chromecast      ] - FrameworkEvent ERROR - org.openhab.binding.chromecast

After startup, when I check the status of my chromecast things, they are all shown as ONLINE:

chromecast:audiogroup:XXXXXXXX-XXXX-XXXX-XXXX-XXXXXXXXXXXXN(Type=Thing, Status=ONLINE, Label=Karns Home, Bridge=null)
chromecast:chromecast:XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (Type=Thing, Status=ONLINE, Label=Living Room, Bridge=null)
chromecast:chromecast:XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (Type=Thing, Status=ONLINE, Label=SHIELD, Bridge=null)
chromecast:chromecast:XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (Type=Thing, Status=ONLINE, Label=Office Home, Bridge=null)

Has anyone else seen similar errors? Does anyone have any ideas for a fix?

vzorglub · December 26, 2018, 8:35pm

Does the binding work?

Post an issue on gitHub anyway. Make sure you add your config and the logs. Thanks

scottk · December 27, 2018, 12:00am

Thanks for the response, @vzorglub. I’m still debugging here. I have seen numerous UI (PaperUI, BasicUI, and Habmin) errors that make me think this may not be a binding issue, but an exhausted SD card problem. If I can definitively determine that my problems are not due to an exhausted SD card, I will open an issue on github.

scottk · December 28, 2018, 6:12pm

I have confirmed that the binding is working and have convinced myself that the cause is not an exhausted microSD card. I opened the following issue:

bussies · December 29, 2018, 11:28am

Same problem here, however not only the Chromecast binding (and things) is (are) gone, but all things discovered by bindings like hue, chromecast, onkyo, samsungtv, zwave, nest, amazonechocontrol and harmonyhub are gone.

addons.cfg:
binding=“modbus1,ntp,tado,http1,tcp1,network,nest,weatherunderground,samsungtv,onkyo,chromecast,hue,zwave,zway,systeminfo,logreader,unifi,amazonechocontrol,harmonyhub”

scottk · December 29, 2018, 4:51pm

Thanks to @wborn, we have a diagnosis of this issue:

The Chromecast Binding JAR inside openhab-addons-2.5.0-SNAPSHOT.kar for build # 1479 contains generated XML files that were generated in some previous build:

50540008-4f93f600-0b8a-11e9-95aa-99fcf8bb23d7.png930×247

The files from December 20th should not be there and contain references to classes that were moved to another package. That explains the exceptions in the log file:
Caused by: java.lang.IllegalStateException: Could not load implementation object class org.openhab.binding.chromecast.internal.ChromecastHandlerFactory
Maybe we can cleanup all modified/untracked files from the Jenkins workspace before building @kaikreuzer ?

See the issue linked in post #4.