Playsound rule squeezer not really work

Marc.pol · October 9, 2019, 4:12pm

This is the debug log

tting up player for notification
2019-10-09 18:09:50.937 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 100
2019-10-09 18:09:51.040 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Adding notification message to playlist
2019-10-09 18:09:51.043 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist add http://127.0.0.1:8080/audio/24df949f-e875-48a2-9acd-c9dc06d4f793.mp3 Notification
2019-10-09 18:09:51.547 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playlist updated
2019-10-09 18:09:51.551 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playing notification
2019-10-09 18:09:51.553 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist index 1
2019-10-09 18:09:53.161 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL http://Haus:1qayYAQ%21@127.0.0.1:9000/html/images/radio.png
2019-10-09 18:10:11.816 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Notification message timed out after 20 seconds
2019-10-09 18:10:11.819 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Restoring player state
2019-10-09 18:10:11.821 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 0
2019-10-09 18:10:11.823 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Removing notification message from playlist
2019-10-09 18:10:11.825 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist delete 1
2019-10-09 18:10:13.430 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playlist updated
2019-10-09 18:10:13.432 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist index 0
2019-10-09 18:10:13.434 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 time 0
2019-10-09 18:10:13.435 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Stopping the player
2019-10-09 18:10:13.437 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 stop
2019-10-09 18:10:13.439 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 70
2019-10-09 18:10:13.441 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - SqueezeBoxTimeoutException during notification: Notification message timed out
2019-10-09 18:10:24.654 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: players 0

Marc.pol · October 9, 2019, 4:18pm

mhilbush · October 9, 2019, 5:06pm

2019-10-08 15:37:13.505 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist add 192.168.2.150:9090/audio/bc0e0beb-754d-4adb-942f-5dd255e2b2cc.mp3 Notification

This looks a little strange. Normally, the IP address would be preceded by http://. In this case, without the http://, I wonder if the LMS is interpreting this as a local file reference. That would seem to make sense given the reference to ///./192.168.2.150:9090 in the LMS log file.

Problem is, I have no idea why the http:// would be missing from the URL.

Marc.pol · October 9, 2019, 5:30pm

No that suits … The fully active directory /// … But where is the folder: 8080 / audio … In the ubuntu directory?

mhilbush · October 9, 2019, 5:48pm

The openHAB web server is what serves the audio content from http://:8080/audio/…

I also have the LMS on the same host that runs openHAB.

For example, when I play a file from conf/sounds using smarthome:audio play barking.mp3 from the karaf console, this is what the binding sends to the LMS.

2019-10-09 13:44:52.653 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:16:3f:1e playlist add http://192.168.1.31:8080/audio/1d3f1ebb-794c-4e4e-8e1d-e261e3e5df0d.mp3 Notification

Marc.pol · October 9, 2019, 7:58pm

at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
... 3 more
Caused by: java.io.IOException: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
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:113) ~[?:?]at sun.nio.ch.IOUtil.write(IOUtil.java:58) ~[?:?]
at sun.nio.ch.IOUtil.write(IOUtil.java:50) ~[?:?]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:466) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:262) ~[80:org.eclipse.jetty.io:9.4.18.v20190429]
at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:393) ~[80:org.eclipse.jetty.io:9.4.18.v20190429]
at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:349) ~[80:org.eclipse.jetty.io:9.4.18.v20190429]
at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:132) ~[80:org.eclipse.jetty.io:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:295) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) ~[92:org.eclipse.jetty.util:9.4.18.v20190429]
... 3 more
2019-10-09 21:52:20.300 [INFO ] [server.session.ServerUserAuthService] - Session openhab@/127.0.0.1:47618 authenticated
2019-10-09 21:52:38.068 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL http://Haus:1qayYAQ%21@127.0.0.1:9000/music/current/cover.jpg?player=b8%3A27%3Aeb%3A12%3Ad2%3Aa9
2019-10-09 21:52:49.869 [DEBUG] [eezebox.internal.SqueezeBoxAudioSink] - Processing audioStream http://127.0.0.1:8080/audio/ae08653d-8b8a-42db-951b-d768e1fe414f.mp3 of format AudioFormat [codec=MP3, container=NONE, ]
2019-10-09 21:52:49.871 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Play notification sound on player b8:27:eb:13:cb:f6 at URI http://127.0.0.1:8080/audio/ae08653d-8b8a-42db-951b-d768e1fe414f.mp3
2019-10-09 21:52:49.874 [DEBUG] [ternal.handler.SqueezeBoxPlayerState] - Cur State: vol=70, mut=NOT MUTED, pwr=ON, stp=STOPPED, ctl=PAUSED, shf=OFF, rpt=OFF, tix=0, tnm=1, tim=0
2019-10-09 21:52:49.876 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Setting up player for notification
2019-10-09 21:52:49.878 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 100
2019-10-09 21:52:49.884 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Adding notification message to playlist
2019-10-09 21:52:49.885 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist add http://127.0.0.1:8080/audio/ae08653d-8b8a-42db-951b-d768e1fe414f.mp3 Notification
2019-10-09 21:52:50.387 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playlist updated
2019-10-09 21:52:50.392 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playing notification
2019-10-09 21:52:50.395 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist index 1
2019-10-09 21:52:51.964 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL http://Haus:1qayYAQ%21@127.0.0.1:9000/html/images/radio.png
2019-10-09 21:52:55.626 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL https://i.ytimg.com/vi/zSKF_YCsbCs/maxresdefault.jpg
2019-10-09 21:52:56.011 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL https://i.ytimg.com/vi/lVlgMEFu1PI/sddefault.jpg
2019-10-09 21:52:56.066 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - Trying to download the content of URL http://Haus:1qayYAQ%21@127.0.0.1:9000/html/images/cover.png
2019-10-09 21:53:10.644 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Notification message timed out after 20 seconds
2019-10-09 21:53:10.645 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Restoring player state
2019-10-09 21:53:10.646 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 0
2019-10-09 21:53:10.647 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Removing notification message from playlist
2019-10-09 21:53:10.648 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist delete 1
2019-10-09 21:53:12.251 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Playlist updated
2019-10-09 21:53:12.252 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 playlist index 0
2019-10-09 21:53:12.252 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 time 0
2019-10-09 21:53:12.252 [DEBUG] [handler.SqueezeBoxNotificationPlayer] - Stopping the player
2019-10-09 21:53:12.252 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 stop
2019-10-09 21:53:12.252 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: b8:27:eb:13:cb:f6 mixer volume 70
2019-10-09 21:53:12.252 [DEBUG] [rnal.handler.SqueezeBoxPlayerHandler] - SqueezeBoxTimeoutException during notification: Notification message timed out
2019-10-09 21:53:24.846 [DEBUG] [rnal.handler.SqueezeBoxServerHandler] - Sending command: players 0

I have no idea what is to do… No sound… CLI INTERFACE works

Is the 8080/audio directory…the same /etc/openhab2/sounds…?

mhilbush · October 9, 2019, 8:08pm

No. The openHAB internal http server exposes /audio/<filename>.mp3, which is what it uses to deliver the audio content (in this case, the contents of the mp3 file in conf/sounds) to external clients.

Try putting the actual IP address back into the squeeze server config (I think the openHAB web server only listens on the interface associated with its physical IP address). Also, if you configured the callback in the binding config, put that back to the default.

Marc.pol · October 9, 2019, 8:36pm

Will try it tomorrow

Thank you for all the help

Marc.pol · October 14, 2019, 8:44pm

It works

Great

Thank you for all