-- Logs begin at Sat 2018-05-05 08:05:29 EEST, end at Sat 2025-03-08 03:52:26 EET. -- Mar 08 03:51:00 volumio volumio[1798]: info: BOOT COMPLETED Mar 08 03:51:05 volumio volumio[1798]: info: sendMpdCommand stop took 34521 milliseconds Mar 08 03:51:05 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: sendMpdCommand clear took 27 milliseconds Mar 08 03:51:05 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand add "http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8" Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: error: updateQueue error: null Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 82ms Mar 08 03:51:05 volumio volumio[1798]: info: sendMpdCommand add "http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8" took 80 milliseconds Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 19ms Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 18ms Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 18ms Mar 08 03:51:05 volumio volumio[1798]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 03:51:05 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand play Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: Mar 08 03:51:05 volumio volumio[1798]: ---------------------------- MPD announces system playlist update Mar 08 03:51:05 volumio volumio[1798]: info: Ignoring MPD Status Update Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 26ms Mar 08 03:51:05 volumio volumio[1798]: info: sendMpdCommand play took 22 milliseconds Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 22ms Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 20ms Mar 08 03:51:05 volumio volumio[1798]: info: ------------------------------ 19ms Mar 08 03:51:05 volumio volumio[1798]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 08 03:51:06 volumio volumio[1798]: info: MyVolumio token set successfully Mar 08 03:51:06 volumio volumio[1798]: info: MYVOLUMIO: Adding device Mar 08 03:51:06 volumio volumio[1798]: info: MYVOLUMIO: Evaluating Server Mar 08 03:51:07 volumio volumio[1798]: info: MyVolumio Plan changed: premium Mar 08 03:51:07 volumio volumio[1798]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 08 03:51:07 volumio volumio[1798]: info: Removing browser output: myVolumio user plan is not superstar Mar 08 03:51:07 volumio volumio[1798]: info: Removing audio output: Mar 08 03:51:07 volumio volumio[1798]: info: MYVOLUMIO: Adding device Mar 08 03:51:07 volumio volumio[1798]: info: MYVOLUMIO: Evaluating Server Mar 08 03:51:07 volumio volumio[1798]: info: Remote config written successfully Mar 08 03:51:07 volumio volumio[1798]: info: Starting Tunnel 1 Mar 08 03:51:07 volumio volumio[1798]: info: Starting Tunnel Connection Checker Mar 08 03:51:08 volumio volumio[1798]: info: MYVolumio Device enabled Mar 08 03:51:08 volumio volumio[1798]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 08 03:51:08 volumio volumio[1798]: info: MyVolumio status changed Mar 08 03:51:08 volumio volumio[1798]: info: Streaming services startup Mar 08 03:51:08 volumio volumio[1798]: info: Starting Streaming Daemon Mar 08 03:51:08 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 03:51:08 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:08 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:08 volumio volumio[1798]: info: Setting Geolocation for MyVolumio to eu6 Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 43 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 41 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 40 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 39 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: info: Mar 08 03:51:08 volumio volumio[1798]: ---------------------------- MPD announces state update: player Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::getState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand status Mar 08 03:51:08 volumio volumio[1798]: error: Cannot start Volumio Streaming Daemon Mar 08 03:51:08 volumio volumio[1798]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 03:51:08 volumio volumio[1798]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 75 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand clearerror took 64 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand clearerror took 63 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand clearerror took 61 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand clearerror took 59 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 30 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 29 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand status took 27 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseState Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 218ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 215ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 212ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 211ms Mar 08 03:51:08 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:08 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:08 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:08 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand clearerror took 162 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 154 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 146 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 146 milliseconds Mar 08 03:51:08 volumio volumio[1798]: info: sendMpdCommand playlistinfo took 144 milliseconds Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: verbose: ControllerMpd::parseTrackInfo Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ControllerMpd::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:08 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:08 volumio volumio[1798]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://lb-hls.cdn.bg/2032/fls/Horizont.stream/playlist.m3u8","trackType":"m3u8"} Mar 08 03:51:08 volumio volumio[1798]: verbose: CURRENT POSITION 0 Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState stateService pause Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::syncState currentStatus stop Mar 08 03:51:08 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:08 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 286ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 234ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 232ms Mar 08 03:51:08 volumio volumio[1798]: info: ------------------------------ 230ms Mar 08 03:51:09 volumio volumio[1798]: info: Setting Geolocation for MyVolumio to eu4 Mar 08 03:51:09 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:09 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:09 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:09 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 08 03:51:09 volumio volumio[1798]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 08 03:51:09 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:09 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:09 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:09 volumio volumio[1798]: info: Successfully Added MyVolumio device Mar 08 03:51:09 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 08 03:51:09 volumio volumio[1798]: info: [1741398669794] Starting BluetoothController Mar 08 03:51:09 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 08 03:51:11 volumio sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 08 03:51:11 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:11 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:11 volumio volumio[1798]: info: MRS: MultiRoom plugin initialized Mar 08 03:51:11 volumio volumio[1798]: info: MRS: STOPPING SNAPCLIENT Mar 08 03:51:11 volumio volumio[1798]: info: MRS: Snap server stop Mar 08 03:51:11 volumio sudo[2250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 08 03:51:11 volumio sudo[2250]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:11 volumio sudo[2250]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:11 volumio volumio[1798]: info: MRS: STOPPING volumioStreaming Mar 08 03:51:11 volumio sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 08 03:51:11 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:11 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:11 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 08 03:51:11 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 08 03:51:11 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:11 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 08 03:51:11 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:11 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 08 03:51:11 volumio volumio[1798]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 08 03:51:11 volumio volumio[1798]: info: Preparing to generate the ALSA configuration file Mar 08 03:51:11 volumio volumio[1798]: info: Updating MyVolumio device info Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: Updating MyVolumio device info Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:11 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 08 03:51:11 volumio volumio[1798]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 03:51:11 volumio volumio[1798]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 08 03:51:11 volumio volumio[1798]: info: Reading ALSA contributions from plugins. Mar 08 03:51:11 volumio volumio[1798]: info: Successfully Added MyVolumio device Mar 08 03:51:11 volumio volumio[1798]: info: MRS: Removed streaming files Mar 08 03:51:11 volumio volumio[1798]: info: MRS: volumioStreaming STOPPED Mar 08 03:51:11 volumio volumio[1798]: info: MRS: SNAPSERVER STOPPED Mar 08 03:51:11 volumio volumio[1798]: info: MRS: SNAPCLIENT STOPPED Mar 08 03:51:12 volumio volumio[1798]: info: Asound.conf file unchanged, so no further update is needed Mar 08 03:51:12 volumio volumio[1798]: info: Output device has changed, restarting MPD Mar 08 03:51:12 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 03:51:12 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:12 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 03:51:12 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:12 volumio systemd[1]: Stopping Music Player Daemon... Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Pushing multiroomSync output for this device Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Pushing multiroomSync output Mar 08 03:51:12 volumio volumio[1798]: info: Adding audio output: Mar 08 03:51:12 volumio volumio[1798]: info: Adding audio output: Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 08 03:51:12 volumio volumio[1798]: info: Adding METAVOLUMIO REST API Endpoints Mar 08 03:51:12 volumio volumio[1798]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 08 03:51:12 volumio volumio[1798]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 08 03:51:12 volumio volumio[1798]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 08 03:51:12 volumio volumio[1798]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 08 03:51:12 volumio volumio[1798]: info: Adding inputs REST Endpoints Mar 08 03:51:12 volumio volumio[1798]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 08 03:51:12 volumio volumio[1798]: info: Scanning Audio Inputs Mar 08 03:51:12 volumio volumio[1798]: info: Checking against Known Cards name Mar 08 03:51:12 volumio volumio[1798]: info: Adding Server instance for streaming Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 08 03:51:12 volumio volumio[1798]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 08 03:51:12 volumio volumio[1798]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 08 03:51:12 volumio volumio[1798]: info: Refreshing TIDAL token Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 08 03:51:12 volumio volumio[1798]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 08 03:51:12 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 08 03:51:12 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:12 volumio volumio[1798]: info: Stopping AccessToken refresher cron for QOBUZ Mar 08 03:51:12 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:12 volumio volumio[1798]: info: AccessToken refresher cron started for QOBUZ Mar 08 03:51:12 volumio volumio[1798]: info: Adding QOBUZ REST API Endpoints Mar 08 03:51:12 volumio volumio[1798]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 08 03:51:12 volumio volumio[1798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 08 03:51:12 volumio volumio[1798]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 08 03:51:12 volumio volumio[1798]: info: MPD Permissions set Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 08 03:51:12 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Found cast device: KNSTB02-52d7876ef12d48b4813f5e7e1b1d6195 Mar 08 03:51:12 volumio volumio[1798]: info: Adding audio output: Mar 08 03:51:12 volumio volumio[1798]: info: MRS: Found cast device: SDSTB01-a0f23e372292c0adf4b5f2b875b3cce9 Mar 08 03:51:12 volumio volumio[1798]: info: Adding audio output: Mar 08 03:51:12 volumio sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 08 03:51:12 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:12 volumio systemd[1]: Started MyVolumio SSH Tunnel. Mar 08 03:51:12 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:12 volumio volumio[1798]: info: Remote SSH Started Mar 08 03:51:12 volumio autossh[2308]: port set to 0, monitoring disabled Mar 08 03:51:12 volumio autossh[2308]: starting ssh (count 1) Mar 08 03:51:12 volumio autossh[2308]: ssh child pid is 2311 Mar 08 03:51:13 volumio volumio[1798]: info: Successfully Updated MyVolumio device Mar 08 03:51:13 volumio volumio[1798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 08 03:51:13 volumio volumio[1798]: info: CoreCommandRouter::volumioGetState Mar 08 03:51:13 volumio volumio[1798]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.QZCGeE2gvgOax54TS5tz8JaJeZ53.fa584cac57724a8ccf8106c4aabd6bc6.state.status' Mar 08 03:51:13 volumio volumio[1798]: info: Access Token successfully retrieved Mar 08 03:51:13 volumio volumio[1798]: error: Hi Res Audio Failed Login: [object Object] Mar 08 03:51:13 volumio volumio[1798]: info: Successfully Updated MyVolumio device Mar 08 03:51:13 volumio volumio[1798]: info: Successfully retrieved User Session From TIDAL Mar 08 03:51:13 volumio volumio[1798]: info: Successfully retrieved User Subscription From TIDAL Mar 08 03:51:13 volumio volumio[1798]: info: Adding TIDAL to Browse Sources Mar 08 03:51:13 volumio volumio[1798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 03:51:13 volumio volumio[1798]: info: [1741398673929] CoreMusicLibrary::Adding element TIDAL Mar 08 03:51:13 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Bandcamp Discover Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Calm Radio Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Mixcloud Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source SoundCloud Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Mother Earth Radio Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Podcast Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source Radio Paradise Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source SWARM Station Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source LastFM Mar 08 03:51:13 volumio volumio[1798]: Cannot find translation for source TIDAL Mar 08 03:51:13 volumio volumio[1798]: info: Stopping AccessToken refresher cron Mar 08 03:51:13 volumio volumio[1798]: info: AccessToken refresher cron started Mar 08 03:51:13 volumio volumio[1798]: info: Adding TIDAL REST API Endpoints Mar 08 03:51:13 volumio volumio[1798]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 08 03:51:15 volumio volumio[1798]: info: TidalConnect service stoped! Mar 08 03:51:15 volumio volumio[1798]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 08 03:51:15 volumio volumio[1798]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 08 03:51:15 volumio sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 08 03:51:15 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:15 volumio systemd[1]: Started Volumio Tidal Connect Service. Mar 08 03:51:15 volumio sudo[2321]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:16 volumio volumio[1798]: info: Executing endpoint tc_getconfig Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 08 03:51:16 volumio vtcs[2323]: STARTING TidalConnect services, version: 1.4.0.34 Mar 08 03:51:16 volumio vtcs[2323]: STARTED TidalConnect services. Mar 08 03:51:16 volumio volumio[1798]: info: Executing endpoint tc_connect Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 08 03:51:16 volumio volumio[1798]: info: Connecting to TidalConnect Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:16 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:16 volumio volumio[1798]: info: MRS: Pushing multiroomSync output update for this device Mar 08 03:51:16 volumio volumio[1798]: info: MRS: Pushing multiroomSync output Mar 08 03:51:16 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:16 volumio volumio[1798]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::servicePushState Mar 08 03:51:16 volumio volumio[1798]: info: CoreStateMachine::pushState Mar 08 03:51:16 volumio volumio[1798]: info: CoreCommandRouter::volumioPushState Mar 08 03:51:16 volumio volumio[1798]: info: MRS: Pushing multiroomSync output update for this device Mar 08 03:51:16 volumio volumio[1798]: info: MRS: Pushing multiroomSync output Mar 08 03:51:16 volumio volumio[1798]: info: CorePlayQueue::getTrack 0 Mar 08 03:51:16 volumio volumio[1798]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 08 03:51:16 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:16 volumio volumio[1798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 08 03:51:16 volumio volumio[1798]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.QZCGeE2gvgOax54TS5tz8JaJeZ53.fa584cac57724a8ccf8106c4aabd6bc6.state.status' Mar 08 03:51:17 volumio volumio[1798]: info: MRS: Getting audio outputs on start Mar 08 03:51:17 volumio volumio[1798]: info: MRS: Requesting all other devices output Mar 08 03:51:18 volumio volumio[1798]: info: TidalConnect service started! Mar 08 03:51:18 volumio systemd[1]: mpd.service: Succeeded. Mar 08 03:51:18 volumio systemd[1]: Stopped Music Player Daemon. Mar 08 03:51:19 volumio systemd[1]: Starting Music Player Daemon... Mar 08 03:51:19 volumio sudo[2345]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 08 03:51:19 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:19 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 08 03:51:19 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Mar 08 03:51:19 volumio systemd[1]: Failed to start Music Player Daemon. Mar 08 03:51:19 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:19 volumio volumio[1798]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Mar 08 03:51:19 volumio volumio[1798]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Mar 08 03:51:19 volumio volumio[1798]: See "systemctl status mpd.service" and "journalctl -xe" for details. Mar 08 03:51:19 volumio systemd[1]: Starting Music Player Daemon... Mar 08 03:51:19 volumio sudo[2350]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 08 03:51:19 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 03:51:19 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root Mar 08 03:51:20 volumio volumiossh-tunnel[2307]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Mar 08 03:51:20 volumio mpd[2352]: Mar 08 03:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 03:51:21 volumio systemd[1]: Started Music Player Daemon. Mar 08 03:51:21 volumio volumio[1798]: error: updateQueue error: null Mar 08 03:51:21 volumio mpd_monitor.sh[639]: MPD restarted due to no mpc output. Mar 08 03:52:00 volumio volumio[1260]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 08 03:52:01 volumio volumio[1260]: ........................................++++ Mar 08 03:52:03 volumio volumio[1260]: ...............................................................++++ Mar 08 03:52:03 volumio volumio[1260]: e is 65537 (0x010001) Mar 08 03:52:03 volumio volumio[1260]: writing RSA key Mar 08 03:52:26 volumio volumio[1798]: error: [now-playing] Error fetching https://openweathermap.org: FetchError: request to https://openweathermap.org/ failed, reason: connect ETIMEDOUT 10.0.0.1:443 Mar 08 03:52:26 volumio volumio[1798]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Mar 08 03:52:26 volumio volumio[1798]: at ClientRequest.emit (events.js:400:28) Mar 08 03:52:26 volumio volumio[1798]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Mar 08 03:52:26 volumio volumio[1798]: at TLSSocket.emit (events.js:400:28) Mar 08 03:52:26 volumio volumio[1798]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 08 03:52:26 volumio volumio[1798]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 08 03:52:26 volumio volumio[1798]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 08 03:52:26 volumio volumio[1798]: (node:1798) UnhandledPromiseRejectionWarning: FetchError: request to https://openweathermap.org/ failed, reason: connect ETIMEDOUT 10.0.0.1:443 Mar 08 03:52:26 volumio volumio[1798]: at ClientRequest. (/data/plugins/user_interface/now_playing/node_modules/node-fetch/lib/index.js:1505:11) Mar 08 03:52:26 volumio volumio[1798]: at ClientRequest.emit (events.js:400:28) Mar 08 03:52:26 volumio volumio[1798]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Mar 08 03:52:26 volumio volumio[1798]: at TLSSocket.emit (events.js:400:28) Mar 08 03:52:26 volumio volumio[1798]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 08 03:52:26 volumio volumio[1798]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 08 03:52:26 volumio volumio[1798]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 08 03:52:26 volumio volumio[1798]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 08 03:52:26 volumio volumio[1798]: (node:1798) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Mar 08 03:52:26 volumio volumio[1798]: (node:1798) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 08 03:52:26 volumio volumio[1798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 03:52:26 volumio volumio[1798]: Error: connect ETIMEDOUT 10.0.0.1:80 Mar 08 03:52:26 volumio volumio[1798]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 08 03:52:26 volumio volumio[1798]: errno: -110, Mar 08 03:52:26 volumio volumio[1798]: code: 'ETIMEDOUT', Mar 08 03:52:26 volumio volumio[1798]: syscall: 'connect', Mar 08 03:52:26 volumio volumio[1798]: address: '10.0.0.1', Mar 08 03:52:26 volumio volumio[1798]: port: 80 Mar 08 03:52:26 volumio volumio[1798]: } Mar 08 03:52:26 volumio volumio[1798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 03:52:26 volumio sudo[2607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 03:51 Mar 08 03:52:26 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"