-- Logs begin at Sat 2024-04-06 16:17:57 UTC, end at Sat 2024-04-06 16:20:40 UTC. -- Apr 06 16:19:00 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:19:01 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:19:01 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:19:01 volumio volumio[985]: info: CoreCommandRouter::volumioGetQueue Apr 06 16:19:01 volumio volumio[985]: info: CoreStateMachine::getQueue Apr 06 16:19:01 volumio volumio[985]: info: CorePlayQueue::getQueue Apr 06 16:19:01 volumio volumio[985]: info: Listing playlists Apr 06 16:19:02 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:02 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:02 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:02 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:02 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:02 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:02 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:02 volumio volumio[985]: info: [1712420342172] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:02 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:02 volumio volumio-streaming-daemon[1230]: Environment: production Apr 06 16:19:02 volumio volumio[985]: info: Fetching Streaming Services browse cache Apr 06 16:19:03 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:03 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:03 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:03 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:03 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:03 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:03 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:03 volumio volumio[985]: info: [1712420343508] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:03 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:04 volumio volumio-remote-updater[654]: [2024-04-06 16:19:04] [connect] Successful connection Apr 06 16:19:04 volumio volumio-remote-updater[654]: [2024-04-06 16:19:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1712420344 101 Apr 06 16:19:05 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:05 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: [1712420345050] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:05 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:05 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:05 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: [1712420345569] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:05 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:05 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:05 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:05 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:05 volumio volumio[985]: info: [1712420345863] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:05 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:07 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:07 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:07 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:07 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:07 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:07 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:07 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:07 volumio volumio[985]: info: [1712420347793] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:07 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:08 volumio volumio[985]: info: CoreCommandRouter::volumioSeek Apr 06 16:19:08 volumio volumio[985]: info: CoreStateMachine::seek Apr 06 16:19:08 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:08 volumio volumio[985]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.68:8200/MediaItems/680.mp3","realUri":"upnp/folder/http://192.168.1.68:8200/ctl/ContentDir@1$6$28$1","type":"song","albumart":"/albumart?cacheid=665&web=Ito%20Yuna/miss%20you/extralarge&path=&metadata=false","artist":"Ito Yuna","album":"miss you","name":"miss you","title":"miss you","duration":303} Apr 06 16:19:08 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:08 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:08 volumio volumio[985]: info: [1712420348654] ControllerUPNPBrowser::seek Apr 06 16:19:08 volumio volumio[985]: info: ControllerMpd::seek Apr 06 16:19:08 volumio volumio[985]: error: Could not seek because there is no mpd connection Apr 06 16:19:08 volumio volumio[985]: info: CoreStateMachine::pushState Apr 06 16:19:08 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:08 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Apr 06 16:19:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 16:19:09 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:09 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:09 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:09 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:09 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:09 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:09 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:09 volumio volumio[985]: info: [1712420349621] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:09 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:11 volumio sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 16:19:11 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:19:11 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 16:19:11 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Apr 06 16:19:11 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:19:11 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Apr 06 16:19:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 06 16:19:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 16:19:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:19:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:19:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetQueue Apr 06 16:19:11 volumio volumio[985]: info: CoreStateMachine::getQueue Apr 06 16:19:11 volumio volumio[985]: info: CorePlayQueue::getQueue Apr 06 16:19:11 volumio volumio[985]: info: Listing playlists Apr 06 16:19:14 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 16:19:14 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:19:14 volumio sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 16:19:14 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Apr 06 16:19:14 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:19:14 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Apr 06 16:19:14 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 06 16:19:14 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:14 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 16:19:14 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:19:14 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:19:14 volumio volumio[985]: info: CoreCommandRouter::volumioGetQueue Apr 06 16:19:14 volumio volumio[985]: info: CoreStateMachine::getQueue Apr 06 16:19:14 volumio volumio[985]: info: CorePlayQueue::getQueue Apr 06 16:19:14 volumio volumio[985]: info: Listing playlists Apr 06 16:19:16 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:16 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:16 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:16 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:16 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:16 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:16 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:16 volumio volumio[985]: info: [1712420356259] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:16 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:19:16 volumio volumio[985]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 06 16:19:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 06 16:19:16 volumio volumio[985]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Apr 06 16:19:16 volumio volumio[985]: info: Received Get System Version Apr 06 16:19:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:19:20 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:19:20 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:19:21 volumio volumio[985]: info: CoreCommandRouter::volumioPlay Apr 06 16:19:21 volumio volumio[985]: verbose: UNSET VOLATILE Apr 06 16:19:21 volumio volumio[985]: info: CoreStateMachine::play index undefined Apr 06 16:19:21 volumio volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:19:21 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:21 volumio volumio[985]: info: CoreStateMachine::startPlaybackTimer Apr 06 16:19:21 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 06 16:19:21 volumio volumio[985]: info: [1712420361744] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 16:19:21 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 16:19:28 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:19:28 volumio volumio[985]: info: CURURI: music-library Apr 06 16:19:28 volumio volumio[985]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Apr 06 16:19:30 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 06 16:19:36 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:19:36 volumio volumio[985]: info: CURURI: music-library Apr 06 16:19:36 volumio volumio[985]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Apr 06 16:20:02 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:20:02 volumio volumio[985]: info: CURURI: music-library Apr 06 16:20:02 volumio volumio[985]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Apr 06 16:20:04 volumio volumio[985]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 06 16:20:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:20:08 volumio volumio[985]: info: CURURI: music-library Apr 06 16:20:08 volumio volumio[985]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: info: Retrieving Cloud Streaming UI Apr 06 16:20:37 volumio volumio[985]: info: Getting Tidal Cloud Configuration Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: info: Getting Qobuz Cloud Configuration Apr 06 16:20:37 volumio volumio[985]: info: Asking plugin for UI Config Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: info: Getting Spotify Cloud Configuration Apr 06 16:20:37 volumio volumio[985]: info: Asking plugin for UI Config Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: info: Saving Spotify Acccount Apr 06 16:20:37 volumio volumio[985]: info: Got Tidal Cloud Configuration Apr 06 16:20:37 volumio volumio[985]: info: Got it Apr 06 16:20:37 volumio volumio[985]: info: Got it Apr 06 16:20:37 volumio volumio[985]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:20:37 volumio volumio[985]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 06 16:20:37 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 06 16:20:39 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 06 16:20:39 volumio volumio[985]: verbose: ControllerMpd::sendMpdCommand rescan Apr 06 16:20:39 volumio volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:20:39 volumio volumio[985]: TypeError: Cannot read property 'then' of undefined Apr 06 16:20:39 volumio volumio[985]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Apr 06 16:20:39 volumio volumio[985]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1825:15) Apr 06 16:20:39 volumio volumio[985]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Apr 06 16:20:39 volumio volumio[985]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:907:26) Apr 06 16:20:39 volumio volumio[985]: at emitTwo (events.js:126:13) Apr 06 16:20:39 volumio volumio[985]: at Socket.emit (events.js:214:7) Apr 06 16:20:39 volumio volumio[985]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 06 16:20:39 volumio volumio[985]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 06 16:20:39 volumio volumio[985]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 06 16:20:39 volumio volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:20:40 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 16:19 Apr 06 16:20:40 volumio sudo[1328]: 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"