Feb 09 08:27:01 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4408. Feb 09 08:27:01 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:01 volumio (python)[13538]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:01 volumio (python)[13538]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:01 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:01 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:01 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:06 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4409. Feb 09 08:27:06 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:06 volumio (python)[13557]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:06 volumio (python)[13557]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:06 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:06 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:06 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:07 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1516. Feb 09 08:27:07 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:07 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:07 volumio upmpdcli[13558]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:27:07 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:27:07 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:27:11 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4410. Feb 09 08:27:11 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:11 volumio (python)[13559]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:11 volumio (python)[13559]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:11 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:11 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:11 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:16 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4411. Feb 09 08:27:16 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:17 volumio (python)[13575]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:17 volumio (python)[13575]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:17 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:17 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:17 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:22 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4412. Feb 09 08:27:22 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:22 volumio (python)[13576]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:22 volumio (python)[13576]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:22 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:22 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:22 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:22 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1517. Feb 09 08:27:22 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:22 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:23 volumio upmpdcli[13577]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:27:23 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:27:23 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:27:27 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4413. Feb 09 08:27:27 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:27 volumio (python)[13595]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:27 volumio (python)[13595]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:27 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:27 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:27 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:32 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4414. Feb 09 08:27:32 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:32 volumio (python)[13596]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:32 volumio (python)[13596]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:32 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:32 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:32 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio ir-mpd.sh[13597]: https://cast.animenexus.net/radio/8000/animenexus Feb 09 08:27:34 volumio ir-mpd.sh[13597]: [playing] #5/6 0:00/0:00 (0%) Feb 09 08:27:34 volumio ir-mpd.sh[13597]: volume: 86% repeat: on random: off single: off consume: off Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 5 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 4 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 3 milliseconds Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":null,"isStreaming":false,"title":"animenexus","artist":null,"album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":null,"isStreaming":false,"title":"animenexus","artist":null,"album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":null,"isStreaming":false,"title":"animenexus","artist":null,"album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 24ms Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 25ms Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 26ms Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 6ms Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 5 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 4ms Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:27:34 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:27:34 volumio volumio[1196]: info: Feb 09 08:27:34 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 10 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 9ms Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 7 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 10ms Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 8 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 6ms Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 5 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 4ms Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand status took 3 milliseconds Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 22ms Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 08:27:34 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":4,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"128 Kbps","isStreaming":false,"title":"Make-Up - Blue Forever","artist":"Radio Anime Nexus","album":null,"uri":"https://cast.animenexus.net/radio/8000/animenexus","trackType":""} Feb 09 08:27:34 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:27:34 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:27:34 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:27:34 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 46ms Feb 09 08:27:34 volumio volumio[1196]: info: ------------------------------ 45ms Feb 09 08:27:35 volumio volumio[1196]: info: ------------------------------ 44ms Feb 09 08:27:35 volumio volumio[1196]: info: ------------------------------ 42ms Feb 09 08:27:35 volumio volumio[1196]: info: ------------------------------ 41ms Feb 09 08:27:37 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4415. Feb 09 08:27:37 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:37 volumio (python)[13613]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:37 volumio (python)[13613]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:37 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:37 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:37 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:38 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1518. Feb 09 08:27:38 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:38 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:38 volumio upmpdcli[13614]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:27:38 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:27:38 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:27:43 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4416. Feb 09 08:27:43 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:43 volumio (python)[13617]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:43 volumio (python)[13617]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:43 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:43 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:43 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:48 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4417. Feb 09 08:27:48 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:48 volumio (python)[13635]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:48 volumio (python)[13635]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:48 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:48 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:48 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:53 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1519. Feb 09 08:27:53 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:53 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:27:53 volumio upmpdcli[13636]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:27:53 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4418. Feb 09 08:27:53 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:27:53 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:27:53 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:53 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:53 volumio (python)[13637]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:53 volumio (python)[13637]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:53 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:53 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:27:58 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4419. Feb 09 08:27:58 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:58 volumio (python)[13653]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:58 volumio (python)[13653]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:27:58 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:27:58 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:27:58 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:03 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4420. Feb 09 08:28:03 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:03 volumio (python)[13668]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:03 volumio (python)[13668]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:03 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:03 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:28:03 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1520. Feb 09 08:28:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:28:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:28:08 volumio upmpdcli[13672]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:28:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:28:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:28:09 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4421. Feb 09 08:28:09 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:09 volumio (python)[13674]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:09 volumio (python)[13674]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:09 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:09 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:28:09 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:28:13 volumio ir-mpd.sh[13675]: RPGN Radio: F.E.A.R. - Docks Combat 2 Feb 09 08:28:13 volumio ir-mpd.sh[13675]: [playing] #6/6 0:01/0:00 (0%) Feb 09 08:28:13 volumio ir-mpd.sh[13675]: volume: 86% repeat: on random: off single: off consume: off Feb 09 08:28:13 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:28:13 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 7 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 6 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 5 milliseconds Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces system playlist update Feb 09 08:28:13 volumio volumio[1196]: info: Ignoring MPD Status Update Feb 09 08:28:13 volumio volumio[1196]: info: Feb 09 08:28:13 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 8ms Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 7 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 6ms Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 5 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 4ms Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand status took 3 milliseconds Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 27ms Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 27ms Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 28ms Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 09 08:28:13 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":5,"seek":1135,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"56 Kbps","isStreaming":false,"title":"F.E.A.R. - Docks Combat 2","artist":"RPGN Radio","album":null,"uri":"https://listen.rpgamers.net/rpgn","trackType":"net/rpgn"} Feb 09 08:28:13 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:13 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:13 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:13 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 40ms Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 41ms Feb 09 08:28:13 volumio volumio[1196]: info: ------------------------------ 39ms Feb 09 08:28:14 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4422. Feb 09 08:28:14 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:14 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:14 volumio (python)[13692]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:14 volumio (python)[13692]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:14 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:28:14 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:19 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4423. Feb 09 08:28:19 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:19 volumio (python)[13695]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:19 volumio (python)[13695]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:19 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:19 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:28:19 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:20 volumio volumio[1196]: info: JP_Radio::JpRadio.#setupRoutes.get=> req.url=/radiko/play/TBS Feb 09 08:28:20 volumio volumio[1196]: info: JP_Radio::JpRadio.startStream: stationId=TBS, query=[] Feb 09 08:28:20 volumio volumio[1196]: info: JP_Radio::Radiko.play: url=https://f-radiko.smartstream.ne.jp/TBS/_definst_/simul-stream.stream/playlist.m3u8 Feb 09 08:28:21 volumio volumio[1196]: error: JP_Radio::genTempChunkM3u8URL error Response code 403 (Forbidden) {"code":"ERR_NON_2XX_3XX_RESPONSE","name":"HTTPError","stack":"HTTPError: Response code 403 (Forbidden)\n at Request. (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/as-promise/index.js:118:42)\n at process.processTicksAndRejections (node:internal/process/task_queues:95:5)","timings":{"connect":1770593300999,"end":1770593301134,"lookup":1770593300986,"phases":{"dns":7,"download":2,"firstByte":33,"request":1,"tcp":13,"tls":99,"total":157,"wait":2},"response":1770593301132,"secureConnect":1770593301098,"socket":1770593300979,"start":1770593300977,"upload":1770593301099}} Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::Retrying stream fetch with new token Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::Radiko.getToken Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::Radiko.auth1 Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::Radiko.getPartialKey Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::Radiko.auth2 Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::JpRadio.startStream: ffmpeg.pid=13697 Feb 09 08:28:21 volumio volumio[1196]: info: JP_Radio::JpRadio.startStream: Streaming started Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio ir-mpd.sh[13696]: http://localhost:9000/radiko/play/TBS Feb 09 08:28:23 volumio ir-mpd.sh[13696]: [playing] #1/6 0:00/0:00 (0%) Feb 09 08:28:23 volumio ir-mpd.sh[13696]: volume: 86% repeat: on random: off single: off consume: off Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 7 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: info: Feb 09 08:28:23 volumio volumio[1196]: ---------------------------- MPD announces state update: player Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::getState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 11 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 9 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 9 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 6 milliseconds Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":null,"isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 35ms Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand status took 21 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseState Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"46 Kbps","isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"46 Kbps","isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"46 Kbps","isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"46 Kbps","isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1521. Feb 09 08:28:23 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 97ms Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 94ms Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 94ms Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 91ms Feb 09 08:28:23 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 75 milliseconds Feb 09 08:28:23 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo Feb 09 08:28:23 volumio volumio[1196]: info: ControllerMpd::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::servicePushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":true,"bitrate":"46 Kbps","isStreaming":false,"title":"TBS","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/TBS","trackType":""} Feb 09 08:28:23 volumio volumio[1196]: verbose: CURRENT POSITION 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play Feb 09 08:28:23 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: CoreStateMachine::pushState Feb 09 08:28:23 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:23 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Feb 09 08:28:23 volumio volumio[1196]: info: ------------------------------ 110ms Feb 09 08:28:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 09 08:28:24 volumio upmpdcli[13713]: Could not open config: /tmp/upmpdcli.conf Feb 09 08:28:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 09 08:28:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 09 08:28:24 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Feb 09 08:28:24 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 08:28:24 volumio volumio[1196]: TypeError: Cannot set properties of undefined (setting 'name') Feb 09 08:28:24 volumio volumio[1196]: at JpRadio.pushSongState (/data/plugins/music_service/jp_radio/dist/lib/radio.js:127:36) Feb 09 08:28:24 volumio volumio[1196]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 09 08:28:24 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 08:28:24 volumio systemd[1]: oled-volumio.service: Scheduled restart job, restart counter is at 4424. Feb 09 08:28:24 volumio systemd[1]: Stopped oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:25 volumio (python)[13727]: oled-volumio.service: Failed to locate executable /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:25 volumio (python)[13727]: oled-volumio.service: Failed at step EXEC spawning /home/volumio/oled_env/bin/python: No such file or directory Feb 09 08:28:25 volumio systemd[1]: Started oled-volumio.service - Volumio OLED Display Daemon. Feb 09 08:28:25 volumio systemd[1]: oled-volumio.service: Main process exited, code=exited, status=203/EXEC Feb 09 08:28:25 volumio systemd[1]: oled-volumio.service: Failed with result 'exit-code'. Feb 09 08:28:25 volumio sudo[13730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 08:27' Feb 09 08:28:25 volumio sudo[13730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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"