-- Logs begin at Thu 2024-04-11 04:02:08 UTC, end at Thu 2024-04-11 04:04:38 UTC. -- Apr 11 04:03:32 voldue systemd[1]: Time has been changed Apr 11 04:03:33 voldue volumio[1104]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::Close All Modals sent Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::Close All Modals sent Apr 11 04:03:33 voldue volumio[1104]: Express server listening on port 3000 Apr 11 04:03:33 voldue volumio[1104]: [Metrics] WebUI: 9s 670.08ms Apr 11 04:03:33 voldue volumio[1104]: Volumio Calling Home Apr 11 04:03:33 voldue volumio[1104]: info: CoreStateMachine::resetVolumioState Apr 11 04:03:33 voldue volumio[1104]: info: CoreStateMachine::getcurrentVolume Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 04:03:33 voldue volumio[1104]: info: CoreStateMachine::pushState Apr 11 04:03:33 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::volumioPushState Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 04:03:33 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 04:03:33 voldue volumio[1104]: info: Setting Device type: Raspberry PI Apr 11 04:03:33 voldue volumio[1104]: error: Cannot initialize MPD Connection: MPD is not running Apr 11 04:03:33 voldue volumio-remote-updater[518]: [2024-04-11 04:03:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1712808155 101 Apr 11 04:03:33 voldue volumio[1104]: info: Reloading queue from file Apr 11 04:03:33 voldue volumio[1104]: info: CoreStateMachine::setRepeat false single undefined Apr 11 04:03:33 voldue volumio[1104]: info: CoreStateMachine::pushState Apr 11 04:03:33 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::volumioPushState Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 04:03:34 voldue volumio[1104]: info: CoreStateMachine::setRandom true Apr 11 04:03:34 voldue volumio[1104]: info: CoreStateMachine::pushState Apr 11 04:03:34 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::volumioPushState Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 04:03:34 voldue volumio[1104]: info: Volumio called home Apr 11 04:03:34 voldue volumio[1104]: info: Starting Shairport Sync Apr 11 04:03:34 voldue volumio[1104]: info: Starting Shairport Sync Apr 11 04:03:34 voldue sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 04:03:34 voldue sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:34 voldue sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 04:03:34 voldue sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:34 voldue systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 11 04:03:34 voldue systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 04:03:34 voldue sudo[1221]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:34 voldue systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 04:03:34 voldue systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 11 04:03:34 voldue systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 04:03:34 voldue sudo[1223]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:34 voldue volumio[1104]: info: Shairport-Sync Started Apr 11 04:03:34 voldue volumio[1104]: Error adding Membership: Error: addMembership EINVAL Apr 11 04:03:34 voldue volumio[1104]: info: Shairport-Sync Started Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::volumioGetState Apr 11 04:03:34 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 04:03:34 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 11 04:03:36 voldue sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 04:03:36 voldue sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:36 voldue sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 04:03:36 voldue sudo[1251]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:36 voldue sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:36 voldue sudo[1253]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:36 voldue sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 11 04:03:36 voldue sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:36 voldue systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 11 04:03:36 voldue systemd[1]: Started UPnP Renderer front-end to MPD. Apr 11 04:03:36 voldue sudo[1263]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:36 voldue volumio[1104]: info: Upmpdcli Daemon Started Apr 11 04:03:36 voldue volumio[1104]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 11 04:03:37 voldue volumio[1104]: info: Adding plugin bluetooth to MyMusic Plugins Apr 11 04:03:37 voldue volumio[1104]: info: Adding plugin cd_controller to MyMusic Plugins Apr 11 04:03:37 voldue volumio[1104]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 11 04:03:37 voldue volumio[1104]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 11 04:03:37 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 04:03:37 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 04:03:37 voldue volumio[1104]: info: Starting MyVolumio Remote Streaming Endpoints Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 11 04:03:37 voldue volumio[1104]: info: Streaming services startup Apr 11 04:03:37 voldue volumio[1104]: info: Starting Streaming Daemon Apr 11 04:03:37 voldue sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 11 04:03:37 voldue sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 04:03:37 voldue systemd[1]: Stopping Volumio Streaming Daemon... Apr 11 04:03:37 voldue systemd[1]: Starting Volumio Streaming Daemon... Apr 11 04:03:37 voldue systemd[1]: Started Volumio Streaming Daemon. Apr 11 04:03:37 voldue volumio[1104]: info: MyVolumio login type: Token Apr 11 04:03:37 voldue volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 11 04:03:37 voldue sudo[1279]: pam_unix(sudo:session): session closed for user root Apr 11 04:03:37 voldue volumio-streaming-daemon[1289]: ############################ Apr 11 04:03:37 voldue volumio-streaming-daemon[1289]: # Volumio Streaming Daemon # Apr 11 04:03:37 voldue volumio-streaming-daemon[1289]: # Running on port 7777 # Apr 11 04:03:37 voldue volumio-streaming-daemon[1289]: ############################ Apr 11 04:03:37 voldue volumio-streaming-daemon[1289]: Environment: production Apr 11 04:03:38 voldue volumio[1104]: info: CoreCommandRouter::volumioGetState Apr 11 04:03:38 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:38 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 04:03:38 voldue volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 04:03:38 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 04:03:38 voldue volumio[1104]: info: Listing playlists Apr 11 04:03:38 voldue volumio[1104]: info: CoreCommandRouter::volumioGetQueue Apr 11 04:03:38 voldue volumio[1104]: info: CoreStateMachine::getQueue Apr 11 04:03:38 voldue volumio[1104]: info: CorePlayQueue::getQueue Apr 11 04:03:38 voldue volumio[1104]: error: MyVolumio Custom Token format not valid, refreshing it Apr 11 04:03:38 voldue volumio[1104]: error: Cannot refresh My Volumio Token: [object Object] Apr 11 04:03:39 voldue volumio[1104]: Discovery: adding b1fd953b-02a0-4909-b238-89c279939fbf Apr 11 04:03:39 voldue volumio[1104]: info: mDNS: Found device voldue Apr 11 04:03:39 voldue volumio[1104]: info: CoreCommandRouter::volumioGetState Apr 11 04:03:39 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:03:40 voldue volumio-streaming-daemon[1289]: Environment: production Apr 11 04:03:40 voldue volumio[1104]: info: Fetching Streaming Services browse cache Apr 11 04:03:41 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 11 04:03:41 voldue volumio[1104]: info: CURURI: music-library Apr 11 04:03:41 voldue volumio[1104]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Apr 11 04:03:57 voldue volumio[1104]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alter%20Bridge/1bd26aec-af3c-4d91-b36e-1376e0fda30b.jpg' Apr 11 04:03:58 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 04:03:58 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 04:04:03 voldue volumio[1104]: info: CoreCommandRouter::volumioPlay Apr 11 04:04:03 voldue volumio[1104]: verbose: UNSET VOLATILE Apr 11 04:04:03 voldue volumio[1104]: info: CoreStateMachine::play index undefined Apr 11 04:04:03 voldue volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 04:04:03 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:03 voldue volumio[1104]: info: CoreStateMachine::startPlaybackTimer Apr 11 04:04:03 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:03 voldue volumio[1104]: verbose: ControllerMpd::clearAddPlayTracks USB/Rock_mp3/Kasabian - SCRIPTVRE (Official Video).mp3 Apr 11 04:04:03 voldue volumio[1104]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 04:04:08 voldue volumio[1104]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 11 04:04:11 voldue volumio[1104]: info: CoreCommandRouter::volumioPlay Apr 11 04:04:11 voldue volumio[1104]: verbose: UNSET VOLATILE Apr 11 04:04:11 voldue volumio[1104]: info: CoreStateMachine::play index undefined Apr 11 04:04:11 voldue volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 04:04:11 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:11 voldue volumio[1104]: info: CoreStateMachine::startPlaybackTimer Apr 11 04:04:11 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:11 voldue volumio[1104]: verbose: ControllerMpd::clearAddPlayTracks USB/Rock_mp3/Kasabian - SCRIPTVRE (Official Video).mp3 Apr 11 04:04:11 voldue volumio[1104]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 04:04:18 voldue volumio[1104]: info: CoreCommandRouter::volumioSeek Apr 11 04:04:18 voldue volumio[1104]: info: CoreStateMachine::seek Apr 11 04:04:18 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:18 voldue volumio[1104]: info: TRACKBLOCK {"uri":"mnt/USB/Rock_mp3/Kasabian - SCRIPTVRE (Official Video).mp3","service":"mpd","name":"SCRIPTVRE (Official Video)","artist":"Kasabian","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=151&web=Kasabian//extralarge&path=%2FUSB%2FRock_mp3&metadata=false","duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Apr 11 04:04:18 voldue volumio[1104]: info: CoreStateMachine::startPlaybackTimer Apr 11 04:04:18 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:18 voldue volumio[1104]: info: ControllerMpd::seek Apr 11 04:04:18 voldue volumio[1104]: error: Could not seek because there is no mpd connection Apr 11 04:04:18 voldue volumio[1104]: info: CoreStateMachine::pushState Apr 11 04:04:18 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:18 voldue volumio[1104]: info: CoreCommandRouter::volumioPushState Apr 11 04:04:18 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 04:04:20 voldue volumio[1104]: info: CoreCommandRouter::volumioPlay Apr 11 04:04:20 voldue volumio[1104]: verbose: UNSET VOLATILE Apr 11 04:04:20 voldue volumio[1104]: info: CoreStateMachine::play index undefined Apr 11 04:04:20 voldue volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 04:04:20 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:20 voldue volumio[1104]: info: CoreStateMachine::startPlaybackTimer Apr 11 04:04:20 voldue volumio[1104]: info: CorePlayQueue::getTrack 0 Apr 11 04:04:20 voldue volumio[1104]: verbose: ControllerMpd::clearAddPlayTracks USB/Rock_mp3/Kasabian - SCRIPTVRE (Official Video).mp3 Apr 11 04:04:20 voldue volumio[1104]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 04:04:36 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 11 04:04:36 voldue volumio[1104]: info: CURURI: artists:// Apr 11 04:04:36 voldue volumio[1104]: error: Failed to execute browseSource: TypeError: Cannot read property 'sendCommand' of undefined Apr 11 04:04:38 voldue volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 11 04:04:38 voldue volumio[1104]: info: CURURI: albums:// Apr 11 04:04:38 voldue volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 04:04:38 voldue volumio[1104]: TypeError: Cannot read property 'sendCommand' of undefined Apr 11 04:04:38 voldue volumio[1104]: at /volumio/app/plugins/music_service/mpd/index.js:2938:22 Apr 11 04:04:38 voldue volumio[1104]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 11 04:04:38 voldue volumio[1104]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 11 04:04:38 voldue volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 04:04:38 voldue sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 04:03 Apr 11 04:04:38 voldue sudo[1327]: 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"