Dec 11 17:12:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Dec 11 17:12:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:00 volumio go-librespot[2775]: go-librespot daemon starting... Dec 11 17:12:00 volumio go-librespot[2776]: time="2025-12-11T17:12:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:00 volumio go-librespot[2776]: time="2025-12-11T17:12:00+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:01 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:01 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:02 volumio nmbd[1052]: [2025/12/11 17:12:02.459703, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 11 17:12:02 volumio nmbd[1052]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.46 for name WORKGROUP<1d>. Dec 11 17:12:02 volumio nmbd[1052]: This response was from IP 192.168.1.63, reporting an IP address of 192.168.1.63. Dec 11 17:12:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Dec 11 17:12:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:03 volumio go-librespot[2797]: go-librespot daemon starting... Dec 11 17:12:04 volumio go-librespot[2798]: time="2025-12-11T17:12:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:04 volumio go-librespot[2798]: time="2025-12-11T17:12:04+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:04 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:04 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Dec 11 17:12:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:07 volumio go-librespot[2808]: go-librespot daemon starting... Dec 11 17:12:07 volumio go-librespot[2809]: time="2025-12-11T17:12:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:07 volumio go-librespot[2809]: time="2025-12-11T17:12:07+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:07 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:07 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Dec 11 17:12:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:10 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:10 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:10 volumio go-librespot[2816]: go-librespot daemon starting... Dec 11 17:12:10 volumio go-librespot[2817]: time="2025-12-11T17:12:10+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:10 volumio go-librespot[2817]: time="2025-12-11T17:12:10+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:13 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:13 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Dec 11 17:12:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:13 volumio go-librespot[2838]: go-librespot daemon starting... Dec 11 17:12:13 volumio go-librespot[2839]: time="2025-12-11T17:12:13+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:13 volumio go-librespot[2839]: time="2025-12-11T17:12:13+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:13 volumio ntpd[949]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 11 17:12:16 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:16 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Dec 11 17:12:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:16 volumio go-librespot[2846]: go-librespot daemon starting... Dec 11 17:12:17 volumio go-librespot[2847]: time="2025-12-11T17:12:17+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:17 volumio go-librespot[2847]: time="2025-12-11T17:12:17+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:19 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:19 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Dec 11 17:12:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:20 volumio go-librespot[2853]: go-librespot daemon starting... Dec 11 17:12:20 volumio go-librespot[2854]: time="2025-12-11T17:12:20+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:20 volumio go-librespot[2854]: time="2025-12-11T17:12:20+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:22 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:22 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Dec 11 17:12:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:23 volumio go-librespot[2874]: go-librespot daemon starting... Dec 11 17:12:23 volumio go-librespot[2875]: time="2025-12-11T17:12:23+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:23 volumio go-librespot[2875]: time="2025-12-11T17:12:23+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:25 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:25 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Dec 11 17:12:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:26 volumio go-librespot[2887]: go-librespot daemon starting... Dec 11 17:12:26 volumio go-librespot[2888]: time="2025-12-11T17:12:26+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:26 volumio go-librespot[2888]: time="2025-12-11T17:12:26+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:28 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:28 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Dec 11 17:12:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:29 volumio go-librespot[2894]: go-librespot daemon starting... Dec 11 17:12:30 volumio go-librespot[2895]: time="2025-12-11T17:12:30+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:30 volumio go-librespot[2895]: time="2025-12-11T17:12:30+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:31 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:31 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Dec 11 17:12:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:33 volumio go-librespot[2915]: go-librespot daemon starting... Dec 11 17:12:33 volumio go-librespot[2916]: time="2025-12-11T17:12:33+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:33 volumio go-librespot[2916]: time="2025-12-11T17:12:33+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:34 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:34 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Dec 11 17:12:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:36 volumio go-librespot[2922]: go-librespot daemon starting... Dec 11 17:12:36 volumio go-librespot[2923]: time="2025-12-11T17:12:36+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:36 volumio go-librespot[2923]: time="2025-12-11T17:12:36+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:37 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:37 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Dec 11 17:12:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:39 volumio go-librespot[2930]: go-librespot daemon starting... Dec 11 17:12:39 volumio go-librespot[2931]: time="2025-12-11T17:12:39+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:39 volumio go-librespot[2931]: time="2025-12-11T17:12:39+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:40 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:40 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Dec 11 17:12:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:42 volumio go-librespot[2952]: go-librespot daemon starting... Dec 11 17:12:43 volumio go-librespot[2953]: time="2025-12-11T17:12:43+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:43 volumio go-librespot[2953]: time="2025-12-11T17:12:43+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:43 volumio volumio[1285]: info: Preload queue cleared Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::ClearQueue Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::stop Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::clearPlayQueue Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::saveQueue Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::volumioPushQueue Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::addQueueItems Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::addQueueItems Dec 11 17:12:43 volumio volumio[1285]: info: Preload queue cleared Dec 11 17:12:43 volumio volumio[1285]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022 Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::volumioPushQueue Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::saveQueue Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::updateTrackBlock Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::getTrackBlock Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::volumioPlay Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::play index 0 Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::stop Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::play index undefined Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:43 volumio volumio[1285]: info: [1765469563341] ControllerWebradio::clearAddPlayTrack Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand stop took 19 milliseconds Dec 11 17:12:43 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:43 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand status took 13 milliseconds Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:43 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand clear took 23 milliseconds Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand status took 19 milliseconds Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:43 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:12:43 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:43 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:43 volumio volumio[1285]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 11 17:12:43 volumio volumio[1285]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 68ms Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 75ms Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:43 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:43 volumio volumio[1285]: error: updateQueue error: null Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 56ms Dec 11 17:12:43 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:43 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" took 248 milliseconds Dec 11 17:12:43 volumio volumio[1285]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 17:12:43 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand play Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:43 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 210ms Dec 11 17:12:43 volumio volumio[1285]: info: Dec 11 17:12:43 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:43 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:43 volumio volumio[1285]: info: sendMpdCommand play took 13 milliseconds Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 12ms Dec 11 17:12:43 volumio volumio[1285]: info: ------------------------------ 6ms Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 7 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 12 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 11 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 5 milliseconds Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bossanova_mobile_aac","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus stop Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 40ms Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bossanova_mobile_aac","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bossanova_mobile_aac","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 178ms Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 177ms Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:45 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 159 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bossanova_mobile_aac","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 233ms Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:45 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:45 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 153ms Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 149 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 90ms Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 88 milliseconds Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces system playlist update Dec 11 17:12:45 volumio volumio[1285]: info: Ignoring MPD Status Update Dec 11 17:12:45 volumio volumio[1285]: info: Dec 11 17:12:45 volumio volumio[1285]: ---------------------------- MPD announces state update: player Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::getState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:12:45 volumio volumio[1285]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display : s reset dpms force on Dec 11 17:12:45 volumio volumio[1285]: /usr/bin/xset: unable to open display ":" Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 34ms Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 30 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 21ms Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand status took 19 milliseconds Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseState Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mayla Da Viola - Get up Stand Up","artist":"bossanova","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mayla Da Viola - Get up Stand Up","artist":"bossanova","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 308ms Dec 11 17:12:45 volumio volumio[1285]: info: ------------------------------ 246ms Dec 11 17:12:45 volumio volumio[1285]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 148 milliseconds Dec 11 17:12:45 volumio volumio[1285]: info: sendMpdCommand playlistinfo took 146 milliseconds Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1081,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mayla Da Viola - Get up Stand Up","artist":"bossanova","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: ControllerMpd::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Dec 11 17:12:45 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Dec 11 17:12:45 volumio volumio[1285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1081,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mayla Da Viola - Get up Stand Up","artist":"bossanova","album":null,"uri":"http://185.33.21.112:80/bossanova_mobile_aac","trackType":""} Dec 11 17:12:45 volumio volumio[1285]: verbose: CURRENT POSITION 0 Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState stateService play Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:12:45 volumio volumio[1285]: info: Received an update from plugin. extracting info from payload Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreStateMachine::pushState Dec 11 17:12:45 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Dec 11 17:12:46 volumio volumio[1285]: info: ------------------------------ 321ms Dec 11 17:12:46 volumio volumio[1285]: info: ------------------------------ 306ms Dec 11 17:12:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Dec 11 17:12:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:46 volumio go-librespot[2971]: go-librespot daemon starting... Dec 11 17:12:46 volumio go-librespot[2972]: time="2025-12-11T17:12:46+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:46 volumio go-librespot[2972]: time="2025-12-11T17:12:46+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:46 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:46 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Dec 11 17:12:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:49 volumio go-librespot[2988]: go-librespot daemon starting... Dec 11 17:12:49 volumio go-librespot[2989]: time="2025-12-11T17:12:49+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:49 volumio go-librespot[2989]: time="2025-12-11T17:12:49+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:49 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:49 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:52 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:52 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Dec 11 17:12:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:52 volumio go-librespot[3016]: go-librespot daemon starting... Dec 11 17:12:52 volumio go-librespot[3017]: time="2025-12-11T17:12:52+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:52 volumio go-librespot[3017]: time="2025-12-11T17:12:52+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:55 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:55 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Dec 11 17:12:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:55 volumio go-librespot[3033]: go-librespot daemon starting... Dec 11 17:12:56 volumio go-librespot[3034]: time="2025-12-11T17:12:56+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:56 volumio go-librespot[3034]: time="2025-12-11T17:12:56+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:12:58 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:12:58 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:12:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Dec 11 17:12:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:12:59 volumio go-librespot[3046]: go-librespot daemon starting... Dec 11 17:12:59 volumio go-librespot[3047]: time="2025-12-11T17:12:59+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:12:59 volumio go-librespot[3047]: time="2025-12-11T17:12:59+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:12:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:12:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:00 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:01 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:01 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Dec 11 17:13:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:02 volumio go-librespot[3076]: go-librespot daemon starting... Dec 11 17:13:02 volumio go-librespot[3077]: time="2025-12-11T17:13:02+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:02 volumio go-librespot[3077]: time="2025-12-11T17:13:02+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:02 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 17:13:04 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:04 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Dec 11 17:13:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:05 volumio go-librespot[3092]: go-librespot daemon starting... Dec 11 17:13:05 volumio go-librespot[3093]: time="2025-12-11T17:13:05+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:05 volumio go-librespot[3093]: time="2025-12-11T17:13:05+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:06 volumio sudo[3105]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 17:13:06 volumio sudo[3103]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 17:13:06 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:06 volumio sudo[3105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:06 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:06 volumio sudo[3105]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:06 volumio volumio[1285]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.151 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:06 volumio sudo[3109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:06 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio sudo[3111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 17:13:06 volumio sudo[3109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:06 volumio sudo[3111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 17:13:06 volumio sudo[3109]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 17:13:06 volumio sudo[3111]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 17:13:06 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:06 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:06 volumio volumio[1285]: info: Listing playlists Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetQueue Dec 11 17:13:06 volumio volumio[1285]: info: CoreStateMachine::getQueue Dec 11 17:13:06 volumio volumio[1285]: info: CorePlayQueue::getQueue Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 17:13:06 volumio volumio[1285]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.151 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 17:13:06 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:07 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 17:13:07 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:07 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:07 volumio volumio[1285]: info: Listing playlists Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::volumioGetQueue Dec 11 17:13:07 volumio volumio[1285]: info: CoreStateMachine::getQueue Dec 11 17:13:07 volumio volumio[1285]: info: CorePlayQueue::getQueue Dec 11 17:13:07 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 17:13:07 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:07 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:08 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 17:13:08 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:08 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:08 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:08 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:08 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:08 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Dec 11 17:13:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:08 volumio go-librespot[3122]: go-librespot daemon starting... Dec 11 17:13:09 volumio go-librespot[3123]: time="2025-12-11T17:13:09+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:09 volumio go-librespot[3123]: time="2025-12-11T17:13:09+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:09 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 11 17:13:09 volumio volumio[1285]: info: MyVolumio login type: Token Dec 11 17:13:09 volumio volumio[1285]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 11 17:13:10 volumio sudo[3134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 17:13:10 volumio sudo[3134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:10 volumio sudo[3136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 17:13:10 volumio sudo[3134]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:10 volumio sudo[3136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:10 volumio sudo[3136]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:10 volumio volumio[1285]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.151 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:10 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 17:13:10 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:10 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: Listing playlists Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetQueue Dec 11 17:13:10 volumio volumio[1285]: info: CoreStateMachine::getQueue Dec 11 17:13:10 volumio volumio[1285]: info: CorePlayQueue::getQueue Dec 11 17:13:10 volumio volumio[1285]: info: Received Get System Info Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:13:10 volumio volumio[1285]: info: Discovery: Getting this device information Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:10 volumio volumio[1285]: info: MyVolumio token set successfully Dec 11 17:13:10 volumio volumio[1285]: info: MYVOLUMIO: Adding device Dec 11 17:13:10 volumio volumio[1285]: info: MYVOLUMIO: Evaluating Server Dec 11 17:13:10 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:10 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 17:13:10 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:11 volumio volumio[1285]: info: CoreCommandRouter::volumioGetState Dec 11 17:13:11 volumio volumio[1285]: info: MyVolumio Plan changed: premium Dec 11 17:13:11 volumio volumio[1285]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 11 17:13:11 volumio volumio[1285]: info: Removing browser output: myVolumio user plan is not superstar Dec 11 17:13:11 volumio volumio[1285]: info: Removing audio output: Dec 11 17:13:11 volumio volumio[1285]: info: MYVOLUMIO: Adding device Dec 11 17:13:11 volumio volumio[1285]: info: MYVOLUMIO: Evaluating Server Dec 11 17:13:11 volumio volumio[1285]: info: Setting Geolocation for MyVolumio to eu6 Dec 11 17:13:11 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:11 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:11 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:11 volumio volumio[1285]: info: Remote config written successfully Dec 11 17:13:11 volumio volumio[1285]: info: Starting Tunnel 1 Dec 11 17:13:11 volumio volumio[1285]: info: Starting Tunnel Connection Checker Dec 11 17:13:12 volumio volumio[1285]: info: MYVolumio Device enabled Dec 11 17:13:12 volumio volumio[1285]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 11 17:13:12 volumio volumio[1285]: info: MyVolumio status changed Dec 11 17:13:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Dec 11 17:13:12 volumio volumio[1285]: info: Streaming services startup Dec 11 17:13:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:12 volumio volumio[1285]: info: Starting Streaming Daemon Dec 11 17:13:12 volumio sudo[3195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 17:13:12 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:12 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 11 17:13:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:12 volumio go-librespot[3193]: go-librespot daemon starting... Dec 11 17:13:12 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 11 17:13:12 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:12 volumio go-librespot[3197]: time="2025-12-11T17:13:12+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:12 volumio go-librespot[3197]: time="2025-12-11T17:13:12+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:12 volumio volumio[1285]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 11 17:13:12 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 11 17:13:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Dec 11 17:13:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:15 volumio go-librespot[3204]: go-librespot daemon starting... Dec 11 17:13:15 volumio go-librespot[3205]: time="2025-12-11T17:13:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:15 volumio go-librespot[3205]: time="2025-12-11T17:13:15+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 11 17:13:16 volumio sudo[3213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 11 17:13:16 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:16 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:16 volumio volumio[1285]: info: MRS: MultiRoom plugin initialized Dec 11 17:13:16 volumio volumio[1285]: info: MRS: STOPPING SNAPCLIENT Dec 11 17:13:16 volumio volumio[1285]: info: MRS: Snap server stop Dec 11 17:13:16 volumio volumio[1285]: info: MRS: STOPPING volumioStreaming Dec 11 17:13:16 volumio sudo[3232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 11 17:13:16 volumio sudo[3232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 11 17:13:16 volumio sudo[3234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 11 17:13:16 volumio sudo[3234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:16 volumio sudo[3236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 11 17:13:16 volumio sudo[3236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:16 volumio sudo[3239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 11 17:13:16 volumio sudo[3239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:16 volumio sudo[3239]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 11 17:13:16 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 11 17:13:17 volumio volumio[1285]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 11 17:13:17 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 11 17:13:17 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 11 17:13:17 volumio sudo[3234]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:17 volumio sudo[3236]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:17 volumio sudo[3232]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:17 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 11 17:13:17 volumio volumio[1285]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 11 17:13:17 volumio volumio[1285]: info: Preparing to generate the ALSA configuration file Dec 11 17:13:17 volumio volumio[1285]: info: Updating MyVolumio device info Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:17 volumio volumio[1285]: info: Setting Geolocation for MyVolumio to eu4 Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 17:13:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 17:13:17 volumio volumio[1285]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 17:13:17 volumio volumio[1285]: info: Reading ALSA contributions from plugins. Dec 11 17:13:17 volumio volumio[1285]: info: Successfully Added MyVolumio device Dec 11 17:13:17 volumio volumio[1285]: info: MRS: Removed streaming files Dec 11 17:13:17 volumio volumio[1285]: info: MRS: volumioStreaming STOPPED Dec 11 17:13:17 volumio volumio[1285]: info: MRS: SNAPSERVER STOPPED Dec 11 17:13:17 volumio volumio[1285]: info: MRS: SNAPCLIENT STOPPED Dec 11 17:13:17 volumio volumio[1285]: error: Cannot start Volumio Streaming Daemon Dec 11 17:13:17 volumio volumio[1285]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 11 17:13:17 volumio volumio[1285]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 11 17:13:17 volumio volumio[1285]: Upnp client error: Error: This socket has been ended by the other party Dec 11 17:13:17 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:17 volumio volumio[1285]: info: Asound.conf file written Dec 11 17:13:17 volumio sudo[3251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 11 17:13:17 volumio sudo[3251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:17 volumio sudo[3256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 17:13:17 volumio sudo[3256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:17 volumio sudo[3256]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 11 17:13:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 11 17:13:18 volumio volumio[1285]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 17:13:18 volumio volumio[1285]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 17:13:18 volumio volumio[1285]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 17:13:18 volumio volumio[1285]: info: Output device has changed, restarting MPD Dec 11 17:13:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 17:13:18 volumio sudo[3251]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:18 volumio volumio[1285]: info: Output device has changed, restarting Shairport Sync Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:18 volumio sudo[3264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 17:13:18 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:18 volumio autossh[3266]: port set to 0, monitoring disabled Dec 11 17:13:18 volumio autossh[3266]: starting ssh (count 1) Dec 11 17:13:18 volumio autossh[3266]: ssh child pid is 3274 Dec 11 17:13:18 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:18 volumio volumio[1285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 17:13:18 volumio volumio[1285]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:13:18 volumio sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 17:13:18 volumio sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:18 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 11 17:13:18 volumio volumio[1285]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 11 17:13:18 volumio volumio[1285]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 11 17:13:18 volumio volumio[1285]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 11 17:13:18 volumio volumio[1285]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 11 17:13:18 volumio volumio[1285]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 11 17:13:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 17:13:18 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 11 17:13:18 volumio volumio[1285]: info: MRS: Pushing multiroomSync output for this device Dec 11 17:13:18 volumio volumio[1285]: info: MRS: Pushing multiroomSync output Dec 11 17:13:18 volumio volumio[1285]: info: Adding audio output: Dec 11 17:13:18 volumio volumio[1285]: info: Adding audio output: Dec 11 17:13:18 volumio volumio[1285]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:13:18 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 17:13:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 17:13:18 volumio systemd[1]: mpd.service: Consumed 9.132s CPU time. Dec 11 17:13:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 17:13:18 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 11 17:13:18 volumio bluetoothd[1049]: Failed to set mode: Failed (0x03) Dec 11 17:13:18 volumio volumio[1285]: info: Adding METAVOLUMIO REST API Endpoints Dec 11 17:13:18 volumio volumio[1285]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:13:18 volumio volumio[1285]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:13:18 volumio volumio[1285]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:13:18 volumio volumio[1285]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:13:18 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 11 17:13:18 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 11 17:13:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 17:13:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 17:13:18 volumio bluetoothd[1049]: Path / reserved for Adv Monitor app :1.122 Dec 11 17:13:18 volumio volumio[1285]: info: Preparing CD Folders Dec 11 17:13:18 volumio volumio[1285]: info: Adding CD REST API Endpoints Dec 11 17:13:18 volumio volumio[1285]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 11 17:13:18 volumio volumio[1285]: info: Starting UDEV Watcher for CD Dec 11 17:13:18 volumio volumio[1285]: info: Detecting CD presence with UDEV Dec 11 17:13:18 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 11 17:13:18 volumio bluetoothd[1049]: Adv Monitor app :1.122 disconnected from D-Bus Dec 11 17:13:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 17:13:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 17:13:18 volumio volumiossh-tunnel[3274]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 11 17:13:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Dec 11 17:13:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:18 volumio go-librespot[3287]: go-librespot daemon starting... Dec 11 17:13:18 volumio go-librespot[3288]: time="2025-12-11T17:13:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:18 volumio go-librespot[3288]: time="2025-12-11T17:13:18+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:19 volumio sudo[3286]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 17:13:19 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 17:13:19 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Dec 11 17:13:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 17:13:21 volumio go-librespot[3300]: go-librespot daemon starting... Dec 11 17:13:22 volumio go-librespot[3303]: time="2025-12-11T17:13:22+01:00" level=info msg="running go-librespot 0.4.0" Dec 11 17:13:22 volumio go-librespot[3303]: time="2025-12-11T17:13:22+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character 'p' looking for beginning of value" Dec 11 17:13:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 17:13:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 17:13:23 volumio volumio[1285]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 11 17:13:23 volumio volumio[1285]: info: Adding inputs REST Endpoints Dec 11 17:13:23 volumio volumio[1285]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 11 17:13:23 volumio volumio[1285]: info: Scanning Audio Inputs Dec 11 17:13:23 volumio volumio[1285]: info: Checking against Known Cards name Dec 11 17:13:23 volumio volumio[1285]: info: Checking against Known Cards name Dec 11 17:13:23 volumio volumio[1285]: info: Adding Server instance for streaming Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 11 17:13:23 volumio volumio[1285]: error: Hi Res Audio Failed Login: Missing Login Data Dec 11 17:13:23 volumio volumio[1285]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 11 17:13:23 volumio volumio[1285]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 11 17:13:23 volumio volumio[1285]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 11 17:13:23 volumio volumio[1285]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 11 17:13:23 volumio volumio[1285]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 11 17:13:23 volumio sudo[3327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 17:13:23 volumio sudo[3327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:23 volumio sudo[3334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 17:13:23 volumio volumio[1285]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 11 17:13:23 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:23 volumio volumio[1285]: info: Adding TIDAL REST API Endpoints Dec 11 17:13:23 volumio volumio[1285]: info: Stopping AccessToken refresher cron for QOBUZ Dec 11 17:13:24 volumio sudo[3327]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio volumio[1285]: info: AccessToken refresher cron started for QOBUZ Dec 11 17:13:24 volumio volumio[1285]: info: Adding QOBUZ REST API Endpoints Dec 11 17:13:24 volumio volumio[1285]: info: Updating MyVolumio device info Dec 11 17:13:24 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:24 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:24 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:13:24 volumio volumio[1285]: info: Initializing connection to go-librespot Websocket Dec 11 17:13:24 volumio volumio[1285]: info: MRS: Getting audio outputs on start Dec 11 17:13:24 volumio volumio[1285]: info: MRS: Requesting all other devices output Dec 11 17:13:24 volumio sudo[3337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 17:13:24 volumio sudo[3337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio volumio[1285]: info: Successfully Updated MyVolumio device Dec 11 17:13:24 volumio mpd[3299]: 2025-12-11T17:13:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 17:13:24 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 17:13:24 volumio volumio[1285]: info: Successfully Added MyVolumio device Dec 11 17:13:24 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 17:13:24 volumio volumio[1285]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Dec 11 17:13:24 volumio sudo[3337]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio sudo[3341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 11 17:13:24 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 17:13:24 volumio sudo[3269]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio sudo[3343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 11 17:13:24 volumio sudo[3343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio sudo[3345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 11 17:13:24 volumio sudo[3351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 11 17:13:24 volumio sudo[3351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio sudo[3345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio sudo[3357]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Dec 11 17:13:24 volumio sudo[3357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio sudo[3357]: pam_unix(sudo:session): session closed for user root Dec 11 17:13:24 volumio volumio[1285]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Dec 11 17:13:24 volumio volumio[1285]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Dec 11 17:13:24 volumio systemd[1]: Reloading. Dec 11 17:13:24 volumio sudo[3360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Dec 11 17:13:24 volumio sudo[3360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:24 volumio volumio[1285]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Dec 11 17:13:24 volumio volumio[1285]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 17:13:24 volumio volumio[1285]: info: MPD Permissions set Dec 11 17:13:24 volumio volumio[1285]: info: Remote SSH Started Dec 11 17:13:24 volumio sudo[3364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 11 17:13:24 volumio sudo[3364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:25 volumio volumio[1285]: info: Successfully Updated MyVolumio device Dec 11 17:13:25 volumio volumio[1285]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 17:13:25 volumio volumio[1285]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 11 17:13:25 volumio volumio[1285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 17:13:25 volumio volumio[1285]: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 17:13:25 volumio volumio[1285]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 11 17:13:25 volumio volumio[1285]: at ChildProcess.exithandler (node:child_process:413:7) Dec 11 17:13:25 volumio volumio[1285]: at ChildProcess.emit (node:events:514:28) Dec 11 17:13:25 volumio volumio[1285]: at maybeClose (node:internal/child_process:1105:16) Dec 11 17:13:25 volumio volumio[1285]: at Socket. (node:internal/child_process:457:11) Dec 11 17:13:25 volumio volumio[1285]: at Socket.emit (node:events:514:28) Dec 11 17:13:25 volumio volumio[1285]: at Pipe. (node:net:337:12) Dec 11 17:13:25 volumio volumio[1285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 17:13:27 volumio sudo[3402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 17:12' Dec 11 17:13:27 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:13:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 11 17:13:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"