-- Logs begin at Tue 2025-04-29 16:03:04 UTC, end at Thu 2025-07-17 13:23:37 UTC. -- Jul 17 13:22:08 volumio29_raspi3 volumio[1375]: info: Tunnel connection is inactive, restarting it Jul 17 13:22:08 volumio29_raspi3 volumio[1375]: info: Starting Tunnel 1 Jul 17 13:22:08 volumio29_raspi3 volumio[1375]: info: Starting Tunnel Connection Checker Jul 17 13:22:09 volumio29_raspi3 sudo[16646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 17 13:22:09 volumio29_raspi3 sudo[16646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:22:09 volumio29_raspi3 systemd[1]: Stopping MyVolumio SSH Tunnel... Jul 17 13:22:09 volumio29_raspi3 autossh[16046]: received signal to exit (15) Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: Playing: false Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: VOLATILE: undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: STATE:undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: Playing: false Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: VOLATILE: undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: STATE:undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: Playing: false Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: VOLATILE: undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: STATE:undefined Jul 17 13:22:09 volumio29_raspi3 systemd[1]: Starting MyVolumio SSH Tunnel... Jul 17 13:22:09 volumio29_raspi3 systemd[1]: Started MyVolumio SSH Tunnel. Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: Playing: false Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: VOLATILE: undefined Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: BT PLUGIN MESSAGE: STATE:undefined Jul 17 13:22:09 volumio29_raspi3 sudo[16646]: pam_unix(sudo:session): session closed for user root Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: info: Remote SSH Started Jul 17 13:22:09 volumio29_raspi3 autossh[16655]: port set to 0, monitoring disabled Jul 17 13:22:09 volumio29_raspi3 autossh[16655]: starting ssh (count 1) Jul 17 13:22:09 volumio29_raspi3 autossh[16655]: ssh child pid is 16659 Jul 17 13:22:09 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:22:09 volumio29_raspi3 volumiossh-tunnel[16652]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: Discovery: Getting this device information Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:22:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:22:30 volumio29_raspi3 volumio[1375]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Jul 17 13:22:32 volumio29_raspi3 volumio[1375]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Jul 17 13:22:32 volumio29_raspi3 volumio[1375]: info: Folder /tmp/plugins removed Jul 17 13:22:33 volumio29_raspi3 volumio[1375]: info: Checking if plugin already exists Jul 17 13:22:33 volumio29_raspi3 volumio[1375]: info: Rename folder Jul 17 13:22:33 volumio29_raspi3 volumio[1375]: info: Folder /tmp/downloaded_plugin.zip removed Jul 17 13:22:33 volumio29_raspi3 volumio[1375]: info: Move to category Jul 17 13:22:34 volumio29_raspi3 volumio[1375]: info: Checking if install.sh is present Jul 17 13:22:34 volumio29_raspi3 volumio[1375]: info: Executing install.sh Jul 17 13:22:35 volumio29_raspi3 sudo[16725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/personal_radio/install.sh Jul 17 13:22:35 volumio29_raspi3 sudo[16725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:22:35 volumio29_raspi3 sudo[16725]: pam_unix(sudo:session): session closed for user root Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Installing PersonalRadio Plugin Dependencies Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: PersonalRadio plugin installed Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Install script completed Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Adding reference to registry Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Done installing plugin. Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Folder /tmp/plugins removed Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Folder /tmp/downloaded_plugin.zip removed Jul 17 13:22:35 volumio29_raspi3 volumio[1375]: info: Folder /data/temp removed Jul 17 13:22:37 volumio29_raspi3 volumio[1375]: info: Enabling plugin personal_radio Jul 17 13:22:37 volumio29_raspi3 volumio[1375]: info: Loading plugin "personal_radio"... Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: ControllerPersonalRadio::constructor Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: PLUGIN START: personal_radio Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: [1752758559336] CoreMusicLibrary::Adding element Personal Radio Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 13:22:39 volumio29_raspi3 volumio[1375]: info: Done. Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: Discovery: Getting this device information Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:22:40 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:22:41 volumio29_raspi3 dhcpd[1402]: DHCPDISCOVER from b8:27:eb:d0:8f:74 (volumio29_raspi3) via wlan0 Jul 17 13:22:42 volumio29_raspi3 dhcpd[1402]: DHCPOFFER on 192.168.211.3 to b8:27:eb:d0:8f:74 (volumio29_raspi3) via wlan0 Jul 17 13:22:42 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Retrieving Cloud Streaming UI Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Getting Tidal Cloud Configuration Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Getting Qobuz Cloud Configuration Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Asking plugin for UI Config Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Getting Spotify Cloud Configuration Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Asking plugin for UI Config Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Saving Spotify Acccount Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Got it Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Got Tidal Cloud Configuration Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: Got it Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetBrowseSources Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:22:43 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 17 13:22:48 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 17 13:22:51 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::ClearQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: verbose: UNSET VOLATILE Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stPlaybackTimer Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::updateTrackBlock Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrackBlock Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 1 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::serviceStop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 1 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::serviceStop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: [1752758573562] ControllerQobuz::stop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: ControllerMpd::stop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::clearPlayQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::saveQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::addQueueItems Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::addQueueItems Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: Adding Item to queue: weblinn/0 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: Exploding uri weblinn/0 in service personal_radio Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::saveQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushQueue Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::updateTrackBlock Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrackBlock Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPlay Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: verbose: UNSET VOLATILE Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::play index 0 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stop Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: verbose: UNSET VOLATILE Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::play index undefined Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::startPlaybackTimer Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:22:53 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 13:22:55 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:22:56 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand stop took 3653 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces state update: player Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand stop took 3557 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand clear Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces system playlist update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Ignoring MPD Status Update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 9 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand clear took 8 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8000/stream" Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: error: updateQueue error: null Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces system playlist update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Ignoring MPD Status Update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 14ms Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand add "http://radio.linnrecords.com:8000/stream" took 8 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand play Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 45ms Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 27ms Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand play took 6 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces state update: player Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces system playlist update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Ignoring MPD Status Update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces state update: player Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 6 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 10ms Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 6 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::pushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::servicePushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: 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":"320 Kbps","isStreaming":false,"title":"stream","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: CURRENT POSITION 0 Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState stateService play Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState currentStatus stop Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 65ms Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces system playlist update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Ignoring MPD Status Update Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces state update: player Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 51 milliseconds Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ControllerMpd::pushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::servicePushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"stream","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: verbose: CURRENT POSITION 0 Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState stateService play Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState currentStatus play Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: Received an update from plugin. extracting info from payload Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:57 volumio29_raspi3 volumio[1375]: info: ------------------------------ 143ms Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: ------------------------------ 528ms Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 527 milliseconds Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: ControllerMpd::pushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::servicePushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Martin Taylor - Czardas [3J]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: verbose: CURRENT POSITION 0 Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState stateService play Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState currentStatus play Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: Received an update from plugin. extracting info from payload Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:22:58 volumio29_raspi3 volumio[1375]: info: ------------------------------ 619ms Jul 17 13:23:00 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:23:06 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:23:07 volumio29_raspi3 sudo[16809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 13:23:07 volumio29_raspi3 sudo[16809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:07 volumio29_raspi3 sudo[16809]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:07 volumio29_raspi3 sudo[16813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 13:23:07 volumio29_raspi3 sudo[16813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:07 volumio29_raspi3 sudo[16813]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:07 volumio29_raspi3 sudo[16827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 13:23:07 volumio29_raspi3 sudo[16827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:07 volumio29_raspi3 sudo[16827]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:07 volumio29_raspi3 sudo[16831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 13:23:07 volumio29_raspi3 sudo[16831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:07 volumio29_raspi3 sudo[16831]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:07 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: Listing playlists Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 17 13:23:08 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetQueue Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::getQueue Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getQueue Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: Discovery: Getting this device information Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:23:10 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 17 13:23:15 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:23:15 volumio29_raspi3 sudo[16866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 13:23:15 volumio29_raspi3 sudo[16866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:15 volumio29_raspi3 sudo[16866]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:15 volumio29_raspi3 sudo[16870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 13:23:15 volumio29_raspi3 sudo[16870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:15 volumio29_raspi3 sudo[16870]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:16 volumio29_raspi3 sudo[16886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 13:23:16 volumio29_raspi3 sudo[16886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:16 volumio29_raspi3 sudo[16886]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:16 volumio29_raspi3 sudo[16890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 13:23:16 volumio29_raspi3 sudo[16890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 13:23:16 volumio29_raspi3 sudo[16890]: pam_unix(sudo:session): session closed for user root Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetState Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: Listing playlists Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 17 13:23:16 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 17 13:23:20 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetQueue Jul 17 13:23:20 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::getQueue Jul 17 13:23:20 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getQueue Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioRemoveQueueItem Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::removeQueueItem Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: UNSET VOLATILE Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stPlaybackTimer Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::updateTrackBlock Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrackBlock Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::serviceStop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::serviceStop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: ControllerMpd::stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::removeQueueItem 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::saveQueue Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushQueue Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: ---------------------------- MPD announces state update: player Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: sendMpdCommand stop took 258 milliseconds Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: ControllerMpd::getState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 8 milliseconds Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: sendMpdCommand status took 7 milliseconds Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: ControllerMpd::parseTrackInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: ControllerMpd::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::servicePushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: 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":"Martin Taylor - Czardas [3J]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: CURRENT POSITION 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState stateService stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState currentStatus stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: No code Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: 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":"Martin Taylor - Czardas [3J]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: verbose: CURRENT POSITION 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState stateService stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::syncState currentStatus stop Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: No code Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::pushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::getTrack 0 Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:21 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:22 volumio29_raspi3 volumio[1375]: info: ------------------------------ 192ms Jul 17 13:23:23 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:23:24 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 13:23:27 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioGetVisibleSources Jul 17 13:23:27 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 13:23:29 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 17 13:23:32 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: Received Get System Version Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::ClearQueue Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::stop Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: verbose: UNSET VOLATILE Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::clearPlayQueue Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::saveQueue Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushState Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreCommandRouter::volumioPushQueue Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CoreStateMachine::addQueueItems Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: CorePlayQueue::addQueueItems Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: Adding Item to queue: webkbs/1 Jul 17 13:23:34 volumio29_raspi3 volumio[1375]: info: Exploding uri webkbs/1 in service personal_radio Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: ReferenceError: self is not defined Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at emitOne (events.js:116:13) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at Request.emit (events.js:211:7) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at emitOne (events.js:116:13) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at ClientRequest.emit (events.js:211:7) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at Socket.socketErrorListener (_http_client.js:387:9) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at emitOne (events.js:116:13) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at Socket.emit (events.js:211:7) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at emitErrorNT (internal/streams/destroy.js:64:8) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 17 13:23:35 volumio29_raspi3 volumio[1375]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 13:23:37 volumio29_raspi3 sudo[16956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 13:22 Jul 17 13:23:37 volumio29_raspi3 sudo[16956]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"