-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-05-09 18:18:11 CEST. -- May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "alarm-clock"... May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "airplay_emulation"... May 09 18:17:00 volumiocaseta volumio[1026]: info: Starting Shairport Sync May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "last_100"... May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "webradio"... May 09 18:17:00 volumiocaseta volumio-remote-updater[586]: [2025-05-09 18:17:00] [connect] Successful connection May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "i2s_dacs"... May 09 18:17:00 volumiocaseta sudo[1208]: pam_unix(sudo:session): session closed for user root May 09 18:17:00 volumiocaseta volumio[1026]: info: Loading plugin "volumiodiscovery"... May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** For more information see May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:17:01 volumiocaseta volumio[1026]: *** WARNING *** For more information see May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** For more information see May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:17:01 volumiocaseta node[1026]: *** WARNING *** For more information see May 09 18:17:01 volumiocaseta volumio[1026]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 18:17:01 volumiocaseta volumio[1026]: info: Discovery: Started advertising with name: Volumio_Caseta May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "outputs"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "albumart"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Plugin example_plugin is not enabled May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "inputs"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "updater_comm"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Plugin mpdemulation is not enabled May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "rest_api"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading plugin "websocket"... May 09 18:17:01 volumiocaseta volumio[1026]: info: Starting Socket.io Server version 2.3.0 May 09 18:17:01 volumiocaseta volumio[1026]: info: Loading i18n strings for locale es May 09 18:17:01 volumiocaseta volumio[1026]: Updating browse sources language May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:17:01 volumiocaseta volumio[1026]: Forking 3 albumart workers May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::initPlayerControls May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:01 volumiocaseta volumio[1026]: Express server listening on port 3000 May 09 18:17:01 volumiocaseta volumio[1026]: [Metrics] WebUI: 7s 236.62ms May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreStateMachine::resetVolumioState May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreStateMachine::getcurrentVolume May 09 18:17:01 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:17:02 volumiocaseta volumio[1026]: info: Volumio Network Manager: Network status updated: 2 May 09 18:17:02 volumiocaseta volumio-remote-updater[586]: [2025-05-09 18:17:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746807420 101 May 09 18:17:02 volumiocaseta volumio[1026]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:02 volumiocaseta volumio[1026]: info: Reloading queue from file May 09 18:17:02 volumiocaseta volumio[1026]: info: VolumeController:: Volume=100 Mute =false May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:02 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::updateTrackBlock May 09 18:17:02 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrackBlock May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:17:02 volumiocaseta volumio[1026]: info: Setting Device type: Raspberry PI May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::setRepeat null single undefined May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:02 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::setRandom null May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:02 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:02 volumiocaseta volumio[1026]: info: Completed loading Core Plugins May 09 18:17:02 volumiocaseta volumio[1026]: info: Preparing to generate the ALSA configuration file May 09 18:17:02 volumiocaseta volumio[1026]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 09 18:17:02 volumiocaseta volumio[1026]: info: Reading ALSA contributions from plugins. May 09 18:17:02 volumiocaseta volumio[1026]: Starting albumart workers May 09 18:17:02 volumiocaseta volumio[1026]: Starting albumart workers May 09 18:17:02 volumiocaseta volumio[1026]: Starting albumart workers May 09 18:17:02 volumiocaseta volumio[1026]: info: Asound.conf file unchanged, so no further update is needed May 09 18:17:02 volumiocaseta volumio[1026]: info: Output device has changed, restarting MPD May 09 18:17:02 volumiocaseta volumio[1026]: info: Output device has changed, restarting Shairport Sync May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:02 volumiocaseta volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 18:17:02 volumiocaseta volumio[1026]: info: ___________ START PLUGINS ___________ May 09 18:17:02 volumiocaseta volumio[1026]: info: ControllerMpd::onStart: Initializing MPD May 09 18:17:02 volumiocaseta volumio[1026]: info: Creating MPD Configuration file May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 18:17:02 volumiocaseta volumio[1026]: info: [1746807422902] CoreMusicLibrary::Adding element Media Servers May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:02 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:03 volumiocaseta sudo[1189]: pam_unix(sudo:session): session closed for user root May 09 18:17:03 volumiocaseta volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 18:17:03 volumiocaseta volumio[1026]: info: [1746807423135] CoreMusicLibrary::Adding element Last_100 May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 18:17:03 volumiocaseta volumio[1026]: info: [1746807423140] CoreMusicLibrary::Adding element Webradio May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:17:03 volumiocaseta volumio[1026]: info: Initializing BBC Radios May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: Volumio Calling Home May 09 18:17:03 volumiocaseta sudo[1287]: volumio : unable to resolve host volumiocaseta May 09 18:17:03 volumiocaseta sudo[1287]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:03 volumiocaseta sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 18:17:03 volumiocaseta sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:03 volumiocaseta sudo[1287]: pam_unix(sudo:session): session closed for user root May 09 18:17:03 volumiocaseta volumio[1026]: info: Discovery: adding 7336e032-8625-4130-8665-70fc7c7d56c7 May 09 18:17:03 volumiocaseta volumio[1026]: info: Discovery: Found device Volumio_Despacho May 09 18:17:03 volumiocaseta volumio[1026]: info: Discovery: Connecting to remote: 192.168.1.4 May 09 18:17:03 volumiocaseta volumio[1026]: info: Discovery: adding a5b56e26-add7-4a56-bfad-f9e9fe748567 May 09 18:17:03 volumiocaseta volumio[1026]: info: Discovery: Found device Volumio_Caseta May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:03 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:03 volumiocaseta volumio[1026]: info: MPD Permissions set May 09 18:17:03 volumiocaseta volumio[1026]: info: VolumeController:: Volume=100 Mute =false May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:03 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:03 volumiocaseta volumio[1026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:03 volumiocaseta volumio[1026]: info: Starting Shairport Sync May 09 18:17:03 volumiocaseta volumio[1026]: info: Starting Shairport Sync May 09 18:17:03 volumiocaseta volumio[1026]: info: Starting Shairport Sync May 09 18:17:03 volumiocaseta sudo[1289]: volumio : unable to resolve host volumiocaseta May 09 18:17:03 volumiocaseta sudo[1289]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:03 volumiocaseta sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 18:17:03 volumiocaseta sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:03 volumiocaseta systemd[1]: Stopping Music Player Daemon... May 09 18:17:04 volumiocaseta systemd[1]: mpd.service: Succeeded. May 09 18:17:04 volumiocaseta systemd[1]: Stopped Music Player Daemon. May 09 18:17:04 volumiocaseta systemd[1]: Starting Music Player Daemon... May 09 18:17:04 volumiocaseta sudo[1294]: volumio : unable to resolve host volumiocaseta May 09 18:17:04 volumiocaseta sudo[1294]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:04 volumiocaseta sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 18:17:04 volumiocaseta sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:04 volumiocaseta sudo[1294]: pam_unix(sudo:session): session closed for user root May 09 18:17:04 volumiocaseta volumio[1026]: info: MPD Permissions set May 09 18:17:04 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:04 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:05 volumiocaseta sudo[1296]: volumio : unable to resolve host volumiocaseta May 09 18:17:05 volumiocaseta sudo[1296]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:05 volumiocaseta sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 18:17:05 volumiocaseta sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:05 volumiocaseta systemd[1]: systemd-fsckd.service: Succeeded. May 09 18:17:05 volumiocaseta sudo[1357]: volumio : unable to resolve host volumiocaseta May 09 18:17:05 volumiocaseta sudo[1354]: volumio : unable to resolve host volumiocaseta May 09 18:17:05 volumiocaseta sudo[1354]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:05 volumiocaseta sudo[1357]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:05 volumiocaseta sudo[1350]: volumio : unable to resolve host volumiocaseta May 09 18:17:05 volumiocaseta sudo[1350]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:05 volumiocaseta sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 18:17:05 volumiocaseta sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:05 volumiocaseta sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 18:17:05 volumiocaseta sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 18:17:05 volumiocaseta sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:05 volumiocaseta sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:05 volumiocaseta systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 18:17:05 volumiocaseta systemd[1]: shairport-sync.service: Succeeded. May 09 18:17:05 volumiocaseta systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 18:17:05 volumiocaseta systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 18:17:05 volumiocaseta sudo[1357]: pam_unix(sudo:session): session closed for user root May 09 18:17:05 volumiocaseta sudo[1354]: pam_unix(sudo:session): session closed for user root May 09 18:17:05 volumiocaseta volumio[1026]: info: Shairport-Sync Started May 09 18:17:05 volumiocaseta sudo[1350]: pam_unix(sudo:session): session closed for user root May 09 18:17:05 volumiocaseta volumio[1026]: Error adding Membership: Error: addMembership EINVAL May 09 18:17:05 volumiocaseta volumio[1026]: info: Shairport-Sync Started May 09 18:17:05 volumiocaseta volumio[1026]: info: Shairport-Sync Started May 09 18:17:05 volumiocaseta sudo[1359]: root : unable to resolve host volumiocaseta May 09 18:17:05 volumiocaseta sudo[1359]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:05 volumiocaseta sudo[1359]: sudo: unable to resolve host volumiocaseta: System error May 09 18:17:05 volumiocaseta sudo[1359]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 18:17:05 volumiocaseta sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:05 volumiocaseta sudo[1359]: pam_unix(sudo:session): session closed for user root May 09 18:17:05 volumiocaseta systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 09 18:17:05 volumiocaseta systemd[1]: mpd.service: Succeeded. May 09 18:17:05 volumiocaseta systemd[1]: Stopped Music Player Daemon. May 09 18:17:05 volumiocaseta volumio[1026]: info: Volumio called home May 09 18:17:05 volumiocaseta systemd[1]: Starting Music Player Daemon... May 09 18:17:05 volumiocaseta volumio[1026]: info: Discovery: Connected to remote: 192.168.1.4 May 09 18:17:06 volumiocaseta sudo[1368]: root : unable to resolve host volumiocaseta May 09 18:17:06 volumiocaseta sudo[1368]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:06 volumiocaseta sudo[1368]: sudo: unable to resolve host volumiocaseta: System error May 09 18:17:06 volumiocaseta sudo[1368]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 18:17:06 volumiocaseta sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:06 volumiocaseta sudo[1368]: pam_unix(sudo:session): session closed for user root May 09 18:17:07 volumiocaseta mpd[1370]: May 09 18:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 18:17:07 volumiocaseta sudo[1373]: volumio : unable to resolve host volumiocaseta May 09 18:17:07 volumiocaseta sudo[1373]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:07 volumiocaseta sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:17:07 volumiocaseta sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:07 volumiocaseta sudo[1373]: pam_unix(sudo:session): session closed for user root May 09 18:17:07 volumiocaseta sudo[1375]: volumio : unable to resolve host volumiocaseta May 09 18:17:07 volumiocaseta sudo[1375]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:07 volumiocaseta sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:17:07 volumiocaseta sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:07 volumiocaseta sudo[1375]: pam_unix(sudo:session): session closed for user root May 09 18:17:07 volumiocaseta volumio[1026]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:17:08 volumiocaseta sh[533]: timed out May 09 18:17:08 volumiocaseta dhcpcd[569]: timed out May 09 18:17:08 volumiocaseta sh[533]: dhcpcd exited May 09 18:17:08 volumiocaseta dhcpcd[569]: dhcpcd exited May 09 18:17:08 volumiocaseta sh[533]: ifup: failed to bring up eth0 May 09 18:17:08 volumiocaseta systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 09 18:17:08 volumiocaseta systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 09 18:17:08 volumiocaseta systemd[1]: Started Music Player Daemon. May 09 18:17:08 volumiocaseta sudo[1296]: pam_unix(sudo:session): session closed for user root May 09 18:17:08 volumiocaseta sudo[1289]: pam_unix(sudo:session): session closed for user root May 09 18:17:08 volumiocaseta sudo[1380]: volumio : unable to resolve host volumiocaseta May 09 18:17:08 volumiocaseta sudo[1380]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:08 volumiocaseta volumio[1026]: info: Completed starting Core Plugins May 09 18:17:08 volumiocaseta volumio[1026]: info: ------------------------------------------- May 09 18:17:08 volumiocaseta volumio[1026]: info: ----- MyVolumio plugins startup ---- May 09 18:17:08 volumiocaseta volumio[1026]: info: ------------------------------------------- May 09 18:17:08 volumiocaseta sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:17:08 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 18:17:08 volumiocaseta sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:08 volumiocaseta sudo[1384]: volumio : unable to resolve host volumiocaseta May 09 18:17:08 volumiocaseta sudo[1384]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:08 volumiocaseta sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:17:08 volumiocaseta sudo[1380]: pam_unix(sudo:session): session closed for user root May 09 18:17:08 volumiocaseta sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:08 volumiocaseta sudo[1384]: pam_unix(sudo:session): session closed for user root May 09 18:17:08 volumiocaseta volumio[1026]: error: MPD error: The expression evaluated to a falsy value: May 09 18:17:08 volumiocaseta volumio[1026]: assert.ok(self.idling) May 09 18:17:08 volumiocaseta volumio[1026]: error: The expression evaluated to a falsy value: May 09 18:17:08 volumiocaseta volumio[1026]: assert.ok(self.idling) May 09 18:17:08 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:08 volumiocaseta volumio[1026]: info: MPD running with PID1370 May 09 18:17:08 volumiocaseta volumio[1026]: ,establishing connection May 09 18:17:08 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:08 volumiocaseta volumio[1026]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:08 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:17:08 volumiocaseta volumio[1026]: info: Received Get System Info May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:17:08 volumiocaseta volumio[1026]: info: Discovery: Getting this device information May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:08 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:08 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:08 volumiocaseta volumio[1026]: info: Listing playlists May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 18:17:08 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 18:17:09 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/%E2%80%9974%20Jailbreak/ab42a52a-0cf3-41e7-80f5-58e911bdf28e.jpg' May 09 18:17:09 volumiocaseta sudo[1404]: volumio : unable to resolve host volumiocaseta May 09 18:17:09 volumiocaseta sudo[1404]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:09 volumiocaseta sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:17:09 volumiocaseta sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:09 volumiocaseta sudo[1404]: pam_unix(sudo:session): session closed for user root May 09 18:17:09 volumiocaseta sudo[1406]: volumio : unable to resolve host volumiocaseta May 09 18:17:09 volumiocaseta sudo[1406]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:09 volumiocaseta sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:17:09 volumiocaseta sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:09 volumiocaseta sudo[1406]: pam_unix(sudo:session): session closed for user root May 09 18:17:09 volumiocaseta sudo[1408]: volumio : unable to resolve host volumiocaseta May 09 18:17:09 volumiocaseta sudo[1408]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:09 volumiocaseta sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 18:17:09 volumiocaseta sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:09 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:09 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:09 volumiocaseta systemd[1]: Started UPnP Renderer front-end to MPD. May 09 18:17:09 volumiocaseta sudo[1408]: pam_unix(sudo:session): session closed for user root May 09 18:17:09 volumiocaseta volumio[1026]: info: Upmpdcli Daemon Started May 09 18:17:09 volumiocaseta volumio[1412]: Generating RSA private key, 4096 bit long modulus (2 primes) May 09 18:17:09 volumiocaseta systemd[1]: systemd-hostnamed.service: Succeeded. May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 18:17:10 volumiocaseta volumio[1026]: info: Received Get System Info May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:17:10 volumiocaseta volumio[1026]: info: Discovery: Getting this device information May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:10 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:10 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:17:12 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:12 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 18:17:12 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 09 18:17:12 volumiocaseta sudo[1434]: volumio : unable to resolve host volumiocaseta May 09 18:17:12 volumiocaseta sudo[1434]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:12 volumiocaseta sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 09 18:17:12 volumiocaseta sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:12 volumiocaseta sudo[1439]: volumio : unable to resolve host volumiocaseta May 09 18:17:12 volumiocaseta sudo[1439]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:12 volumiocaseta sudo[1434]: pam_unix(sudo:session): session closed for user root May 09 18:17:12 volumiocaseta sudo[1445]: volumio : unable to resolve host volumiocaseta May 09 18:17:12 volumiocaseta sudo[1445]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:12 volumiocaseta sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 09 18:17:12 volumiocaseta sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:12 volumiocaseta sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 09 18:17:12 volumiocaseta sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:12 volumiocaseta sudo[1445]: pam_unix(sudo:session): session closed for user root May 09 18:17:12 volumiocaseta sudo[1439]: pam_unix(sudo:session): session closed for user root May 09 18:17:13 volumiocaseta sudo[1450]: volumio : unable to resolve host volumiocaseta May 09 18:17:13 volumiocaseta sudo[1450]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:13 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 09 18:17:13 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 09 18:17:13 volumiocaseta sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 09 18:17:13 volumiocaseta sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:13 volumiocaseta sudo[1450]: pam_unix(sudo:session): session closed for user root May 09 18:17:13 volumiocaseta sudo[1455]: volumio : unable to resolve host volumiocaseta May 09 18:17:13 volumiocaseta sudo[1455]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:13 volumiocaseta sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:17:13 volumiocaseta sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:13 volumiocaseta sudo[1460]: volumio : unable to resolve host volumiocaseta May 09 18:17:13 volumiocaseta sudo[1460]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:13 volumiocaseta sudo[1455]: pam_unix(sudo:session): session closed for user root May 09 18:17:13 volumiocaseta sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:17:13 volumiocaseta sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:13 volumiocaseta sudo[1460]: pam_unix(sudo:session): session closed for user root May 09 18:17:13 volumiocaseta sudo[1464]: volumio : unable to resolve host volumiocaseta May 09 18:17:13 volumiocaseta sudo[1464]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:13 volumiocaseta sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 18:17:13 volumiocaseta sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:13 volumiocaseta volumio[1412]: ......................++++ May 09 18:17:14 volumiocaseta volumio[1026]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 09 18:17:17 volumiocaseta sudo[1464]: pam_unix(sudo:session): session closed for user root May 09 18:17:18 volumiocaseta volumio[1412]: .........................................++++ May 09 18:17:18 volumiocaseta volumio[1412]: e is 65537 (0x010001) May 09 18:17:18 volumiocaseta volumio[1412]: writing RSA key May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin bluetooth to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin multiroom to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin metavolumio to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin cd_controller to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: Adding plugin tidalconnect to MyMusic Plugins May 09 18:17:19 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 18:17:20 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 18:17:20 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 18:17:20 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:20 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:20 volumiocaseta volumio[1026]: info: Starting MyVolumio Remote Streaming Endpoints May 09 18:17:20 volumiocaseta volumio[1026]: info: MyVolumio login type: Token May 09 18:17:20 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 18:17:20 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 18:17:22 volumiocaseta volumio[1026]: info: Starting Streaming Service Transparent Proxy May 09 18:17:22 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 18:17:22 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 18:17:22 volumiocaseta volumio[1026]: info: Streaming services startup May 09 18:17:22 volumiocaseta volumio[1026]: info: Starting Streaming Daemon May 09 18:17:22 volumiocaseta sudo[1519]: volumio : unable to resolve host volumiocaseta May 09 18:17:22 volumiocaseta sudo[1519]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:22 volumiocaseta volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 09 18:17:22 volumiocaseta sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 18:17:22 volumiocaseta sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:22 volumiocaseta sudo[1519]: pam_unix(sudo:session): session closed for user root May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:22 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPlay May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreStateMachine::play index undefined May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:17:22 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:22 volumiocaseta volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 09 18:17:22 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:22 volumiocaseta volumio[1026]: verbose: ControllerMpd::clearAddPlayTracks NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3 May 09 18:17:22 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:17:22 volumiocaseta volumio[1026]: error: Cannot start Volumio Streaming Daemon May 09 18:17:22 volumiocaseta volumio[1026]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 18:17:22 volumiocaseta volumio[1026]: sudo: unable to resolve host volumiocaseta: System error May 09 18:17:22 volumiocaseta volumio[1026]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 18:17:23 volumiocaseta volumio[1026]: STREAMING PROXY: Starting server on port 3245 May 09 18:17:23 volumiocaseta volumio[1026]: Node JS runtime: 14 May 09 18:17:24 volumiocaseta systemd[1]: systemd-timedated.service: Succeeded. May 09 18:17:26 volumiocaseta volumio[1026]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 18:17:27 volumiocaseta volumio[1026]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 18:17:27 volumiocaseta volumio[1026]: info: Received Get System Version May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:17:27 volumiocaseta volumio[1026]: info: Received Get System Info May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:17:27 volumiocaseta volumio[1026]: info: Discovery: Getting this device information May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:27 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:27 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:17:29 volumiocaseta volumio[1026]: info: MyVolumio token set successfully May 09 18:17:29 volumiocaseta volumio[1026]: info: MYVOLUMIO: Adding device May 09 18:17:29 volumiocaseta volumio[1026]: info: MYVOLUMIO: Evaluating Server May 09 18:17:30 volumiocaseta volumio[1026]: info: MyVolumio status changed May 09 18:17:30 volumiocaseta volumio[1026]: info: Streaming services startup May 09 18:17:30 volumiocaseta volumio[1026]: info: Starting Streaming Daemon May 09 18:17:30 volumiocaseta volumio[1026]: info: Removing browser output: myVolumio user plan is not superstar May 09 18:17:30 volumiocaseta volumio[1026]: info: Removing audio output: May 09 18:17:30 volumiocaseta volumio[1026]: info: Stoppping Tunnel 1 May 09 18:17:30 volumiocaseta sudo[1567]: volumio : unable to resolve host volumiocaseta May 09 18:17:30 volumiocaseta sudo[1567]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:30 volumiocaseta sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 18:17:30 volumiocaseta sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:30 volumiocaseta sudo[1569]: volumio : unable to resolve host volumiocaseta May 09 18:17:30 volumiocaseta sudo[1569]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:17:30 volumiocaseta sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 09 18:17:30 volumiocaseta sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:17:30 volumiocaseta sudo[1567]: pam_unix(sudo:session): session closed for user root May 09 18:17:30 volumiocaseta sudo[1569]: pam_unix(sudo:session): session closed for user root May 09 18:17:33 volumiocaseta volumio[1026]: Cannot compose Albumart path May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:17:33 volumiocaseta volumio[1026]: info: sendMpdCommand stop took 11667 milliseconds May 09 18:17:33 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear May 09 18:17:33 volumiocaseta volumio[1026]: info: Setting Geolocation for MyVolumio to eu10 May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:33 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPlay May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::play index undefined May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::clearAddPlayTracks NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:17:34 volumiocaseta volumio[1026]: info: Remote SSH Stopped May 09 18:17:34 volumiocaseta volumio[1026]: error: Cannot start Volumio Streaming Daemon May 09 18:17:34 volumiocaseta volumio[1026]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 18:17:34 volumiocaseta volumio[1026]: sudo: unable to resolve host volumiocaseta: System error May 09 18:17:34 volumiocaseta volumio[1026]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand clear took 86 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand stop took 31 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand add "NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3" May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio-remote-updater[586]: No test mode May 09 18:17:34 volumiocaseta volumio-remote-updater[586]: No alpha test mode May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 09 18:17:34 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:34 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 39ms May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand add "NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3" took 35 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand clear took 35 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 32ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 30ms May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand play May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand add "NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3" May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:34 volumiocaseta volumio[1026]: error: updateQueue error: null May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 25ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 25ms May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand play took 20 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand add "NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3" took 20 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 17ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 15ms May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand play May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces system playlist update May 09 18:17:34 volumiocaseta volumio[1026]: info: Ignoring MPD Status Update May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 15ms May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand play took 10 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 10ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 7ms May 09 18:17:34 volumiocaseta volumio[1026]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

    • QOBUZ Connect Ready

 

","title":"Update v3.807","updateavailable":true} May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 45 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 50 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 47 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 7 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 5 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus stop May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 71ms May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta volumio[1026]: info: May 09 18:17:34 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 17 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 17 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 18 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 7 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand status took 5 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:34 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:34 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:34 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 161ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 160ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 122ms May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 87 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 87 milliseconds May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:34 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:34 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:34 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 141ms May 09 18:17:34 volumiocaseta volumio[1026]: info: ------------------------------ 140ms May 09 18:17:34 volumiocaseta volumio[1026]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioSeek May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::seek May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: TRACKBLOCK {"uri":"music-library/NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","service":"mpd","name":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=674&web=AC%2FDC/%E2%80%9974%20Jailbreak/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAcdc%2F'74%20Jailbreak&metadata=false","duration":279,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: ControllerMpd::seek May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:34 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:34 volumiocaseta volumio[1026]: info: Updating MyVolumio device info May 09 18:17:34 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:35 volumiocaseta volumio[1026]: error: null May 09 18:17:35 volumiocaseta volumio[1026]: info: May 09 18:17:35 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:35 volumiocaseta volumio[1026]: info: May 09 18:17:35 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:35 volumiocaseta volumio[1026]: info: May 09 18:17:35 volumiocaseta volumio[1026]: ---------------------------- MPD announces state update: player May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::getState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand status took 15 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand status took 14 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand status took 11 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseState May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 18:17:35 volumiocaseta volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:35 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:35 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:35 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:35 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: ControllerMpd::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::servicePushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Jailbreak","artist":"AC/DC","album":"’74 Jailbreak","uri":"NAS/TrueNAS/Acdc/'74 Jailbreak/01 - Jailbreak.mp3","trackType":"mp3"} May 09 18:17:35 volumiocaseta volumio[1026]: verbose: CURRENT POSITION 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState stateService play May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 09 18:17:35 volumiocaseta volumio[1026]: info: Received an update from plugin. extracting info from payload May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:35 volumiocaseta volumio[1026]: info: ------------------------------ 86ms May 09 18:17:35 volumiocaseta volumio[1026]: info: ------------------------------ 85ms May 09 18:17:35 volumiocaseta volumio[1026]: info: ------------------------------ 84ms May 09 18:17:35 volumiocaseta volumio[1026]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 18:17:35 volumiocaseta volumio[1026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 09 18:17:35 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:35 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:38 volumiocaseta volumio[1026]: info: MYVOLUMIO: Adding device May 09 18:17:38 volumiocaseta volumio[1026]: info: MYVOLUMIO: Evaluating Server May 09 18:17:39 volumiocaseta volumio[1026]: info: Setting Geolocation for MyVolumio to eu7 May 09 18:17:39 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:39 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:39 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:17:40 volumiocaseta volumio[1026]: info: Updating MyVolumio device info May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:40 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:41 volumiocaseta volumio[1026]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 09 18:17:42 volumiocaseta volumio[1026]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 09 18:17:42 volumiocaseta volumio[1026]: info: Completed starting MyVolumio Plugin May 09 18:17:42 volumiocaseta volumio[1026]: [Metrics] CommandRouter: 46s 287.21ms May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumiosetStartupVolume May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:17:42 volumiocaseta volumio[1026]: info: VolumeController:: Setting startup Volume 100 May 09 18:17:42 volumiocaseta volumio[1026]: info: VolumeController::SetAlsaVolume100 May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::Close All Modals sent May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::Close All Modals sent May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreStateMachine::pushState May 09 18:17:42 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:17:42 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioPushState May 09 18:17:42 volumiocaseta volumio[1026]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 09 18:17:42 volumiocaseta volumio[1026]: aplay: main:828: audio open error: Device or resource busy May 09 18:17:43 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 18:17:43 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 18:17:43 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: info: Retrieving Cloud Streaming UI May 09 18:17:44 volumiocaseta volumio[1026]: info: Getting Tidal Cloud Configuration May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: info: Getting Qobuz Cloud Configuration May 09 18:17:44 volumiocaseta volumio[1026]: info: Asking plugin for UI Config May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: info: Getting Spotify Cloud Configuration May 09 18:17:44 volumiocaseta volumio[1026]: info: Asking plugin for UI Config May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: info: Saving Spotify Acccount May 09 18:17:44 volumiocaseta volumio[1026]: info: Got Tidal Cloud Configuration May 09 18:17:44 volumiocaseta volumio[1026]: info: Got it May 09 18:17:44 volumiocaseta volumio[1026]: info: Got it May 09 18:17:44 volumiocaseta volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:17:44 volumiocaseta volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 18:17:44 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 09 18:17:47 volumiocaseta ntpd[805]: Soliciting pool server 195.95.153.59 May 09 18:17:48 volumiocaseta ntpd[805]: Soliciting pool server 195.20.235.143 May 09 18:17:48 volumiocaseta ntpd[805]: Soliciting pool server 195.95.153.43 May 09 18:17:48 volumiocaseta ntpd[805]: Soliciting pool server 217.160.115.110 May 09 18:17:48 volumiocaseta ntpd[805]: Soliciting pool server 212.227.232.46 May 09 18:17:48 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 18:17:49 volumiocaseta ntpd[805]: Soliciting pool server 162.159.200.123 May 09 18:17:49 volumiocaseta ntpd[805]: Soliciting pool server 90.68.206.60 May 09 18:17:49 volumiocaseta ntpd[805]: Soliciting pool server 178.255.228.77 May 09 18:17:49 volumiocaseta ntpd[805]: Soliciting pool server 185.198.109.227 May 09 18:17:49 volumiocaseta volumio[1026]: info: BOOT COMPLETED May 09 18:17:50 volumiocaseta ntpd[805]: Soliciting pool server 84.77.195.114 May 09 18:17:50 volumiocaseta ntpd[805]: Soliciting pool server 5.250.184.159 May 09 18:17:50 volumiocaseta ntpd[805]: Soliciting pool server 83.97.79.109 May 09 18:17:51 volumiocaseta ntpd[805]: Soliciting pool server 89.140.186.3 May 09 18:17:51 volumiocaseta ntpd[805]: Soliciting pool server 217.160.163.33 May 09 18:17:52 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetState May 09 18:17:52 volumiocaseta volumio[1026]: info: CorePlayQueue::getTrack 0 May 09 18:17:53 volumiocaseta volumio[1026]: info: CoreCommandRouter::volumioGetQueue May 09 18:17:53 volumiocaseta volumio[1026]: info: CoreStateMachine::getQueue May 09 18:17:53 volumiocaseta volumio[1026]: info: CorePlayQueue::getQueue May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01cc6379 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebc8a933.066165e2 May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01ba9440 does not match aorg 0000000000.00000000 from server@217.160.163.33 xmt 0xebc8a933.0744ea26 May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01cea76b does not match aorg 0000000000.00000000 from server@212.227.232.46 xmt 0xebc8a933.06eaa0f7 May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01c3ec7c does not match aorg 0000000000.00000000 from server@5.250.184.159 xmt 0xebc8a933.07192fe6 May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01c9c305 does not match aorg 0000000000.00000000 from server@178.255.228.77 xmt 0xebc8a933.074f0a74 May 09 18:17:55 volumiocaseta ntpd[805]: receive: Unexpected origin timestamp 0xebc8a932.01c70056 does not match aorg 0000000000.00000000 from server@90.68.206.60 xmt 0xebc8a933.091053b1 May 09 18:17:56 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 18:17:56 volumiocaseta volumio[1026]: info: CURURI: albums:// May 09 18:17:56 volumiocaseta volumio[1026]: info: listAlbums - loading Albums from cache May 09 18:17:56 volumiocaseta volumio[1026]: info: Preload queue cleared May 09 18:17:58 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/%E2%80%9974%20Jailbreak/ab42a52a-0cf3-41e7-80f5-58e911bdf28e.jpg' May 09 18:17:58 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Ballbreaker/b06fa36c-ea0e-4e78-9a91-e42074b540a1.png' May 09 18:17:59 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Blow%20Up%20Your%20Video/67fc9f35-96aa-40b3-99d6-c64e0f2cfaf3.jpg' May 09 18:17:59 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Bonfire%20Disc%201/f79cf57b-76f2-4857-af3a-71bfe8e446b4.jpg' May 09 18:17:59 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Highway%20To%20Hell/227fb411-b412-4d98-8895-43e91e85fcad.png' May 09 18:18:00 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/High%20Voltage/ddd66689-f36d-4705-8c0a-298cd5751e1e.png' May 09 18:18:01 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Powerage/f1c0ce82-ead6-4fd9-b415-9c72a933cd45.png' May 09 18:18:01 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Let%20There%20Be%20Rock/a5c70a22-d935-463d-bbd1-a7a29a5b9be6.jpg' May 09 18:18:01 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Who%20Made%20Who/b6aafdb4-d404-4160-ab63-01aa90fcba0b.png' May 09 18:18:02 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/For%20Those%20About%20To%20Rock%20(We%20Salute%20You)/204f50a7-fb1e-40ae-8d0e-cad33d5f321a.png' May 09 18:18:03 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/If%20You%20Want%20Blood%20You%27ve%20Got%20I/de24938c-a3cc-4e5d-9b4e-eb3c3126d7c6.jpg' May 09 18:18:03 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AC%2FDC/Flick%20Of%20The%20Switch/4ae07fb0-5c3b-43f1-a2b5-6e574df14a8b.jpg' May 09 18:18:04 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska%20Y%20Los%20Pegamoides/Grandes%20%C3%89xitos/c643e4da-1132-4c71-bf65-3395fd24dea1.jpg' May 09 18:18:04 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska/The%20Platinum%20Collection/37925a79-aa5a-4020-bb68-65c7f3e7ef9b.jpg' May 09 18:18:05 volumiocaseta volumio[1026]: info: UPDATER: Scheduling automatic update May 09 18:18:05 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime May 09 18:18:05 volumiocaseta volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime May 09 18:18:05 volumiocaseta volumio[1026]: info: UPDATER: Auto update will take place at: Sat May 10 2025 03:39:46 GMT+0200 (Central European Summer Time) May 09 18:18:05 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska%20Y%20Dinarama/No%20Es%20Pecado%20(Edici%C3%B3n%20Para%20Coleccionistas)/f84f16be-39ed-477f-b2c2-93da24a7c91f.jpg' May 09 18:18:06 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska%20Y%20Dinarama/No%20Es%20Pecado/af6ac77f-71bd-4047-a9b6-b31092c3b1ea.jpg' May 09 18:18:06 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska%20Y%20Dinarama/Fan%20Fatal/281f4f4f-f22d-49ed-8743-f9510e5d25bc.jpg' May 09 18:18:06 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alaska%20Y%20Dinarama/Grandes%20Exitos/7bf43c7c-8c40-4122-afb5-9200acbd5d28.jpg' May 09 18:18:06 volumiocaseta volumio[1026]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fangoria/Salto%20mortal/8bf69908-6781-4e89-9630-8cd96464605f.jpg' May 09 18:18:10 volumiocaseta volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:18:10 volumiocaseta volumio[1026]: Error: self signed certificate May 09 18:18:10 volumiocaseta volumio[1026]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 09 18:18:10 volumiocaseta volumio[1026]: at TLSSocket.emit (events.js:315:20) May 09 18:18:10 volumiocaseta volumio[1026]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 09 18:18:10 volumiocaseta volumio[1026]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 09 18:18:10 volumiocaseta volumio[1026]: code: 'DEPTH_ZERO_SELF_SIGNED_CERT' May 09 18:18:10 volumiocaseta volumio[1026]: } May 09 18:18:10 volumiocaseta volumio[1026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:18:11 volumiocaseta sudo[1779]: volumio : unable to resolve host volumiocaseta May 09 18:18:11 volumiocaseta sudo[1779]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 09 18:18:11 volumiocaseta sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 18:17 May 09 18:18:11 volumiocaseta sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"