-- Logs begin at Thu 2024-08-22 10:22:50 UTC, end at Sat 2024-08-24 03:27:46 UTC. -- Aug 24 03:26:32 volumio volumio[1185]: info: Following Redirect to: http://plugins.volumio.workers.dev/plugins/volumio/armhf/plugins.json Aug 24 03:26:36 volumio volumio[1185]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Aug 24 03:26:39 volumio volumio[1185]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Aug 24 03:26:39 volumio volumio[1185]: info: Folder /tmp/plugins removed Aug 24 03:26:39 volumio volumio[1185]: info: Checking if plugin already exists Aug 24 03:26:39 volumio volumio[1185]: info: Rename folder Aug 24 03:26:39 volumio volumio[1185]: info: Folder /tmp/downloaded_plugin.zip removed Aug 24 03:26:39 volumio volumio[1185]: info: Move to category Aug 24 03:26:44 volumio volumio[1185]: info: Checking if install.sh is present Aug 24 03:26:44 volumio volumio[1185]: info: Executing install.sh Aug 24 03:26:44 volumio sudo[27777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/personal_radio/install.sh Aug 24 03:26:44 volumio sudo[27777]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 03:26:44 volumio sudo[27777]: pam_unix(sudo:session): session closed for user root Aug 24 03:26:44 volumio volumio[1185]: info: Installing PersonalRadio Plugin Dependencies Aug 24 03:26:44 volumio volumio[1185]: info: PersonalRadio plugin installed Aug 24 03:26:44 volumio volumio[1185]: info: Install script completed Aug 24 03:26:44 volumio volumio[1185]: info: Adding reference to registry Aug 24 03:26:44 volumio volumio[1185]: info: Done installing plugin. Aug 24 03:26:44 volumio volumio[1185]: info: Folder /tmp/plugins removed Aug 24 03:26:44 volumio volumio[1185]: info: Folder /tmp/downloaded_plugin.zip removed Aug 24 03:26:44 volumio volumio[1185]: info: Folder /data/temp removed Aug 24 03:26:46 volumio volumio[1185]: info: Enabling plugin personal_radio Aug 24 03:26:46 volumio volumio[1185]: info: Loading plugin "personal_radio"... Aug 24 03:26:47 volumio volumio[1185]: info: ControllerPersonalRadio::constructor Aug 24 03:26:47 volumio volumio[1185]: info: PLUGIN START: personal_radio Aug 24 03:26:47 volumio volumio[1185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 03:26:47 volumio volumio[1185]: info: [1724470007580] CoreMusicLibrary::Adding element 개인 라디오 Aug 24 03:26:47 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 03:26:47 volumio volumio[1185]: info: Done. Aug 24 03:26:49 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 24 03:26:49 volumio volumio[1185]: info: CoreCommandRouter::volumioGetQueue Aug 24 03:26:49 volumio volumio[1185]: info: CoreStateMachine::getQueue Aug 24 03:26:49 volumio volumio[1185]: info: CorePlayQueue::getQueue Aug 24 03:26:53 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 24 03:26:58 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 03:26:58 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Aug 24 03:26:58 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:26:58 volumio volumio[1185]: info: CoreStateMachine::serviceStop Aug 24 03:26:58 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Aug 24 03:26:59 volumio volumio[1185]: info: Airplay Stop Aug 24 03:26:59 volumio volumio[1185]: info: Stopping Airplay Playback and sending pause command to client via USR2 Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Aug 24 03:26:59 volumio volumio[1185]: info: Adding Item to queue: webmbc/0 Aug 24 03:26:59 volumio volumio[1185]: info: Exploding uri webmbc/0 in service personal_radio Aug 24 03:26:59 volumio sudo[27817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1319 Aug 24 03:26:59 volumio sudo[27817]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Aug 24 03:26:59 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:26:59 volumio sudo[27817]: pam_unix(sudo:session): session closed for user root Aug 24 03:26:59 volumio volumio[1185]: info: Stopping Airplay Playback and sending pause command to client via USR2 Aug 24 03:26:59 volumio shairport-sync[1319]: {"time":1724465123200,"response":"startAirplayPlayback Success"} Aug 24 03:26:59 volumio systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Aug 24 03:26:59 volumio systemd[1]: Unit shairport-sync.service entered failed state. Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::play index 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:26:59 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::serviceStop Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Aug 24 03:26:59 volumio volumio[1185]: info: ControllerMpd::stop Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand stop took 12 milliseconds Aug 24 03:26:59 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:26:59 volumio volumio[1185]: info: Shairport-Sync paused with USR2 Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 03:26:59 volumio sudo[27828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1724494741&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0NDk0NzQxfX19XX0_&Signature=k6rQeaL5nDIFDu42sjeOE8S1FUnIrC4tD-MBsEgxlhVJ9ACP7YTdL28BXNp9vc7DBhqMteVppG3bdM~lKs3CRNo88kYBvJRinnUu2JecnZyCmF3v0ptjHmZTwb5Og1jtU2iH1MSv5BK6AJLOn-tMwquSC85p962kSWa0QMbzZbbN~NPc3OJWctipMYycKr03~Yg39-8iSma6w-F--aW6LJaPFCxw15umea7lcUyEDA8Jk8AKWhRx8idOdiWtgm-eSbKsGjRErfLx5r1GT8q0TH7lcEfmkidElxflaoXBqarFe0rs8VoTImQ0870HQksK2B-MYIOp3eGy3kaMRluGjg__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Aug 24 03:26:59 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::syncState stateService stop Aug 24 03:26:59 volumio sudo[27828]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 03:26:59 volumio sudo[27828]: pam_unix(sudo:session): session closed for user root Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: No code Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::play index undefined Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand stop took 8 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 03:26:59 volumio volumio[1185]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Aug 24 03:26:59 volumio volumio[1185]: kill: invalid argument U Aug 24 03:26:59 volumio volumio[1185]: Usage: Aug 24 03:26:59 volumio volumio[1185]: kill [options] [...] Aug 24 03:26:59 volumio volumio[1185]: Options: Aug 24 03:26:59 volumio volumio[1185]: [...] send signal to every listed Aug 24 03:26:59 volumio volumio[1185]: -, -s, --signal Aug 24 03:26:59 volumio volumio[1185]: specify the to be sent Aug 24 03:26:59 volumio volumio[1185]: -l, --list=[] list all signal names, or convert one to a name Aug 24 03:26:59 volumio volumio[1185]: -L, --table list all signal names in a nice table Aug 24 03:26:59 volumio volumio[1185]: -h, --help display this help and exit Aug 24 03:26:59 volumio volumio[1185]: -V, --version output version information and exit Aug 24 03:26:59 volumio volumio[1185]: For more details see kill(1). Aug 24 03:26:59 volumio volumio[1185]: info: Aug 24 03:26:59 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:26:59 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand clear took 9 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Aug 24 03:26:59 volumio volumio[1185]: error: updateQueue error: null Aug 24 03:26:59 volumio volumio[1185]: info: Aug 24 03:26:59 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:26:59 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:26:59 volumio volumio[1185]: info: ------------------------------ 5ms Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" took 3 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Aug 24 03:26:59 volumio volumio[1185]: info: ------------------------------ 9ms Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand play took 5 milliseconds Aug 24 03:26:59 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand status took 3 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 24 03:26:59 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio nmbd[961]: [2024/08/24 03:26:59.260897, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 24 03:26:59 volumio nmbd[961]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 24 03:26:59 volumio volumio[1185]: info: Aug 24 03:26:59 volumio volumio[1185]: ---------------------------- MPD announces state update: player Aug 24 03:26:59 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand clearerror took 2 milliseconds Aug 24 03:26:59 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 03:26:59 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:26:59 volumio volumio[1185]: info: ControllerMpd::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:26:59 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:26:59 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:26:59 volumio volumio[1185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 24 03:26:59 volumio volumio[1185]: info: ------------------------------ 15ms Aug 24 03:27:00 volumio volumio[1185]: info: Restarting Shairport-Sync after stop Aug 24 03:27:00 volumio sudo[27843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 03:27:00 volumio sudo[27843]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 03:27:00 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 24 03:27:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 03:27:00 volumio sudo[27843]: pam_unix(sudo:session): session closed for user root Aug 24 03:27:00 volumio volumio[1185]: info: Shairport-Sync retarted Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:08 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::serviceStop Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Aug 24 03:27:08 volumio volumio[1185]: info: ControllerMpd::stop Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Aug 24 03:27:08 volumio volumio[1185]: info: Adding Item to queue: webmbc/0 Aug 24 03:27:08 volumio volumio[1185]: info: Exploding uri webmbc/0 in service personal_radio Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand stop took 18 milliseconds Aug 24 03:27:08 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand status took 1 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 24 03:27:08 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::syncState stateService stop Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:08 volumio volumio[1185]: info: No code Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Aug 24 03:27:08 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::play index 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:08 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::play index undefined Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand stop took 5 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 03:27:08 volumio volumio[1185]: info: Aug 24 03:27:08 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:08 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand clear took 2 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Aug 24 03:27:08 volumio volumio[1185]: error: updateQueue error: null Aug 24 03:27:08 volumio volumio[1185]: info: Aug 24 03:27:08 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:08 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:08 volumio volumio[1185]: info: ------------------------------ 3ms Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" took 2 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Aug 24 03:27:08 volumio volumio[1185]: info: ------------------------------ 5ms Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand play took 2 milliseconds Aug 24 03:27:08 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand status took 2 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:08 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 03:27:08 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 24 03:27:08 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Aug 24 03:27:08 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:08 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:08 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:09 volumio volumio[1185]: info: Aug 24 03:27:09 volumio volumio[1185]: ---------------------------- MPD announces state update: player Aug 24 03:27:09 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:09 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:09 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 24 03:27:09 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Aug 24 03:27:09 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:09 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:09 volumio volumio[1185]: info: sendMpdCommand clearerror took 3 milliseconds Aug 24 03:27:09 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 03:27:09 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:09 volumio volumio[1185]: info: ControllerMpd::pushState Aug 24 03:27:09 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Aug 24 03:27:09 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:09 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:09 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:09 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:09 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:09 volumio volumio[1185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 24 03:27:09 volumio volumio[1185]: info: ------------------------------ 18ms Aug 24 03:27:19 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 24 03:27:19 volumio volumio[1185]: info: CURURI: music-library Aug 24 03:27:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 24 03:27:20 volumio volumio[1185]: info: CURURI: music-library/USB Aug 24 03:27:20 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 24 03:27:20 volumio volumio[1185]: info: CURURI: music-library/USB/music17 Aug 24 03:27:23 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 24 03:27:27 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:29 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::serviceStop Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Aug 24 03:27:29 volumio volumio[1185]: info: ControllerMpd::stop Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Aug 24 03:27:29 volumio volumio[1185]: info: Adding Item to queue: weblinn/0 Aug 24 03:27:29 volumio volumio[1185]: info: Exploding uri weblinn/0 in service personal_radio Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:27:29 volumio volumio[1185]: info: CoreCommandRouter::volumioPlay Aug 24 03:27:29 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::play index 0 Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:29 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::play index undefined Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::startPlaybackTimer Aug 24 03:27:29 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand stop took 34 milliseconds Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand stop took 14 milliseconds Aug 24 03:27:29 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 03:27:29 volumio volumio[1185]: info: Aug 24 03:27:29 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:29 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand status took 3 milliseconds Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand clear took 3 milliseconds Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8000/stream" Aug 24 03:27:29 volumio volumio[1185]: error: updateQueue error: null Aug 24 03:27:29 volumio volumio[1185]: info: Aug 24 03:27:29 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:29 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:29 volumio volumio[1185]: info: ------------------------------ 5ms Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand add "http://radio.linnrecords.com:8000/stream" took 4 milliseconds Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:29 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand play Aug 24 03:27:29 volumio volumio[1185]: info: ------------------------------ 7ms Aug 24 03:27:29 volumio volumio[1185]: info: sendMpdCommand play took 2 milliseconds Aug 24 03:27:29 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 03:27:30 volumio volumio[1185]: info: Aug 24 03:27:30 volumio volumio[1185]: ---------------------------- MPD announces state update: player Aug 24 03:27:30 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:30 volumio pulseaudio[27944]: [pulseaudio] server-lookup.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 24 03:27:30 volumio pulseaudio[27944]: [pulseaudio] main.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 24 03:27:30 volumio pulseaudio[27944]: [pulseaudio] bluez4-util.c: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Aug 24 03:27:30 volumio bluetoothd[873]: Endpoint registered: sender=:1.134 path=/MediaEndpoint/A2DPSource Aug 24 03:27:30 volumio bluetoothd[873]: Endpoint registered: sender=:1.134 path=/MediaEndpoint/A2DPSink Aug 24 03:27:30 volumio volumio[1185]: info: Aug 24 03:27:30 volumio volumio[1185]: ---------------------------- MPD announces state update: player Aug 24 03:27:30 volumio volumio[1185]: info: sendMpdCommand status took 231 milliseconds Aug 24 03:27:30 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:30 volumio volumio[1185]: info: Aug 24 03:27:30 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:30 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:30 volumio volumio[1185]: info: sendMpdCommand status took 4 milliseconds Aug 24 03:27:30 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 24 03:27:30 volumio volumio[1185]: info: ------------------------------ 4ms Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:30 volumio volumio[1185]: info: ControllerMpd::pushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Aug 24 03:27:30 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:30 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tommy Smith - There is a Mirror Only You Can","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Aug 24 03:27:30 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus stop Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:30 volumio volumio[1185]: info: ------------------------------ 251ms Aug 24 03:27:30 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 24 03:27:30 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:30 volumio volumio[1185]: info: ControllerMpd::pushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Aug 24 03:27:30 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:30 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tommy Smith - There is a Mirror Only You Can","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Aug 24 03:27:30 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Aug 24 03:27:30 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:30 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:30 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:30 volumio volumio[1185]: info: ------------------------------ 30ms Aug 24 03:27:36 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 24 03:27:41 volumio volumio[1185]: info: Aug 24 03:27:41 volumio volumio[1185]: ---------------------------- MPD announces system playlist update Aug 24 03:27:41 volumio volumio[1185]: info: Ignoring MPD Status Update Aug 24 03:27:41 volumio volumio[1185]: info: Aug 24 03:27:41 volumio volumio[1185]: ---------------------------- MPD announces state update: player Aug 24 03:27:41 volumio volumio[1185]: info: ControllerMpd::getState Aug 24 03:27:41 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand status Aug 24 03:27:41 volumio volumio[1185]: info: ------------------------------ 3ms Aug 24 03:27:41 volumio volumio[1185]: info: sendMpdCommand status took 3 milliseconds Aug 24 03:27:41 volumio volumio[1185]: verbose: ControllerMpd::parseState Aug 24 03:27:41 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 03:27:41 volumio volumio[1185]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 03:27:41 volumio volumio[1185]: verbose: ControllerMpd::parseTrackInfo Aug 24 03:27:41 volumio volumio[1185]: info: ControllerMpd::pushState Aug 24 03:27:41 volumio volumio[1185]: info: CoreCommandRouter::servicePushState Aug 24 03:27:41 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:41 volumio volumio[1185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11580,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Alyn Cosker - Smiling Down [5N]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Aug 24 03:27:41 volumio volumio[1185]: verbose: CURRENT POSITION 0 Aug 24 03:27:41 volumio volumio[1185]: info: CoreStateMachine::syncState stateService play Aug 24 03:27:41 volumio volumio[1185]: info: CoreStateMachine::syncState currentStatus play Aug 24 03:27:41 volumio volumio[1185]: info: Received an update from plugin. extracting info from payload Aug 24 03:27:41 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:41 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:41 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:41 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:41 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:41 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:41 volumio volumio[1185]: info: ------------------------------ 26ms Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::ClearQueue Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::stop Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 03:27:45 volumio volumio[1185]: UNSET VOLATILE Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::stPlaybackTimer Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::updateTrackBlock Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::getTrackBlock Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::pushState Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::serviceStop Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::getTrack 0 Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::serviceStop Aug 24 03:27:45 volumio volumio[1185]: info: ControllerMpd::stop Aug 24 03:27:45 volumio volumio[1185]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::clearPlayQueue Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::saveQueue Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::volumioPushState Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 03:27:45 volumio volumio[1185]: info: CoreCommandRouter::volumioPushQueue Aug 24 03:27:45 volumio volumio[1185]: info: CoreStateMachine::addQueueItems Aug 24 03:27:45 volumio volumio[1185]: info: CorePlayQueue::addQueueItems Aug 24 03:27:45 volumio volumio[1185]: info: Adding Item to queue: webkbs/0 Aug 24 03:27:45 volumio volumio[1185]: info: Exploding uri webkbs/0 in service personal_radio Aug 24 03:27:46 volumio volumio[1185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 03:27:46 volumio volumio[1185]: ReferenceError: self is not defined Aug 24 03:27:46 volumio volumio[1185]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Aug 24 03:27:46 volumio volumio[1185]: at /data/plugins/music_service/personal_radio/index.js:487:12 Aug 24 03:27:46 volumio volumio[1185]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Aug 24 03:27:46 volumio volumio[1185]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Aug 24 03:27:46 volumio volumio[1185]: at emitTwo (events.js:126:13) Aug 24 03:27:46 volumio volumio[1185]: at Request.emit (events.js:214:7) Aug 24 03:27:46 volumio volumio[1185]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Aug 24 03:27:46 volumio volumio[1185]: at emitOne (events.js:116:13) Aug 24 03:27:46 volumio volumio[1185]: at Request.emit (events.js:211:7) Aug 24 03:27:46 volumio volumio[1185]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Aug 24 03:27:46 volumio volumio[1185]: at emitNone (events.js:111:20) Aug 24 03:27:46 volumio volumio[1185]: at IncomingMessage.emit (events.js:208:7) Aug 24 03:27:46 volumio volumio[1185]: at endReadableNT (_stream_readable.js:1064:12) Aug 24 03:27:46 volumio volumio[1185]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Aug 24 03:27:46 volumio volumio[1185]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 24 03:27:46 volumio volumio[1185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 03:27:46 volumio sudo[27989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 03:26 Aug 24 03:27:46 volumio sudo[27989]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="9dfacc3084ddf8378a0a2ba052bab3e7aa2ae345" VOLUMIO_BE_VERSION="40a5d4b5232c61241c1a813b8652ad2b0cbd8a2c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 5 14:48:15 UTC 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="f911710eb3a296dbaa3280d30d30bab2"