-- Logs begin at Sat 2024-04-13 18:47:12 +05, end at Sun 2024-04-14 18:30:57 +05. -- Apr 14 18:29:02 volumio volumio[23599]: info: Preload queue cleared Apr 14 18:29:02 volumio volumio[23599]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:29:02 volumio volumio[23599]: info: CoreStateMachine::ClearQueue Apr 14 18:29:02 volumio volumio[23599]: info: CoreStateMachine::stop Apr 14 18:29:02 volumio volumio[23599]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:02 volumio volumio[23599]: info: CorePlayQueue::clearPlayQueue Apr 14 18:29:02 volumio volumio[23599]: info: CorePlayQueue::saveQueue Apr 14 18:29:02 volumio volumio[23599]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:29:02 volumio volumio[23599]: info: CoreStateMachine::addQueueItems Apr 14 18:29:02 volumio volumio[23599]: info: CorePlayQueue::addQueueItems Apr 14 18:29:02 volumio volumio[23599]: info: Preload queue cleared Apr 14 18:29:02 volumio volumio[23599]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:29:02 volumio volumio[23599]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:29:02 volumio volumio[23599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:02 volumio volumio[23599]: TypeError: Cannot read property 'then' of undefined Apr 14 18:29:02 volumio volumio[23599]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 14 18:29:02 volumio volumio[23599]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 14 18:29:02 volumio volumio[23599]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 14 18:29:02 volumio volumio[23599]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 14 18:29:02 volumio volumio[23599]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 14 18:29:02 volumio volumio[23599]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 14 18:29:02 volumio volumio[23599]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 14 18:29:02 volumio volumio[23599]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 14 18:29:02 volumio volumio[23599]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 14 18:29:02 volumio volumio[23599]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 14 18:29:02 volumio volumio[23599]: at Socket.emit (events.js:315:20) Apr 14 18:29:02 volumio volumio[23599]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 18:29:02 volumio volumio[23599]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 18:29:02 volumio volumio[23599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:02 volumio sudo[23723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:28 Apr 14 18:29:02 volumio sudo[23723]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:02 volumio sudo[23723]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:03 volumio volumio-remote-updater[457]: [2024-04-14 18:29:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 18:29:03 volumio volumio-remote-updater[457]: [2024-04-14 18:29:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 18:29:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 18:29:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 18:29:03 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:03 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 18:29:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 14 18:29:03 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:03 volumio systemd[1]: Stopped Volumio Backend Module. Apr 14 18:29:03 volumio systemd[1]: Started Volumio Backend Module. Apr 14 18:29:03 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:05 volumio volumio[23735]: info: ------------------------------------------- Apr 14 18:29:05 volumio volumio[23735]: info: ----- Volumio3 ---- Apr 14 18:29:05 volumio volumio[23735]: info: ------------------------------------------- Apr 14 18:29:05 volumio volumio[23735]: info: ----- System startup ---- Apr 14 18:29:05 volumio volumio[23735]: info: ------------------------------------------- Apr 14 18:29:05 volumio volumio[23735]: info: MYVOLUMIO Environment detected Apr 14 18:29:05 volumio volumio[23735]: info: Plugin folders cleanup Apr 14 18:29:05 volumio volumio[23735]: info: Scanning into folder /volumio/app/plugins/ Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category audio_interface Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category miscellanea Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category music_service Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category plugins.json Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category system_controller Apr 14 18:29:05 volumio volumio[23735]: info: Scanning category user_interface Apr 14 18:29:05 volumio volumio[23735]: info: Scanning into folder /data/plugins/ Apr 14 18:29:05 volumio volumio[23735]: info: Plugin folders cleanup completed Apr 14 18:29:05 volumio volumio[23735]: info: ------------------------------------------- Apr 14 18:29:05 volumio volumio[23735]: info: ----- Core plugins startup ---- Apr 14 18:29:05 volumio volumio[23735]: info: ------------------------------------------- Apr 14 18:29:05 volumio volumio[23735]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 18:29:05 volumio volumio[23735]: info: Adding plugin upnp to MyMusic Plugins Apr 14 18:29:05 volumio volumio[23735]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 18:29:05 volumio volumio[23735]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 18:29:05 volumio volumio[23735]: info: Loading plugins from folder /data/plugins/ Apr 14 18:29:05 volumio volumio[23735]: info: Loading plugin "system"... Apr 14 18:29:05 volumio volumio[23735]: info: Loading plugin "appearance"... Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "network"... Apr 14 18:29:06 volumio volumio[23735]: info: Refreshing Cached IP Addresses Apr 14 18:29:06 volumio sudo[23761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:06 volumio sudo[23761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:06 volumio sudo[23761]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:06 volumio sudo[23763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "services"... Apr 14 18:29:06 volumio sudo[23763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "alsa_controller"... Apr 14 18:29:06 volumio sudo[23763]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:06 volumio sudo[23766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 18:29:06 volumio sudo[23766]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:06 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "wizard"... Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "networkfs"... Apr 14 18:29:06 volumio volumio[23735]: info: Cannot mount NAS Music-PC at system boot, trial number 1 ,retrying in 5 seconds Apr 14 18:29:06 volumio volumio[23735]: info: Starting Udev Watcher for removable devices Apr 14 18:29:06 volumio volumio[23735]: info: Ignoring mount for partition: boot Apr 14 18:29:06 volumio volumio[23735]: info: Ignoring mount for partition: volumio Apr 14 18:29:06 volumio volumio[23735]: info: Ignoring mount for partition: volumio_data Apr 14 18:29:06 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "volumio_command_line_client"... Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "upnp"... Apr 14 18:29:06 volumio volumio[23735]: info: [1713101346964] Starting Upmpd Daemon Apr 14 18:29:06 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "my_music"... Apr 14 18:29:06 volumio volumio[23735]: info: Loading plugin "mpd"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "upnp_browser"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "alarm-clock"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "airplay_emulation"... Apr 14 18:29:07 volumio volumio[23735]: info: Starting Shairport Sync Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "last_100"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "webradio"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "i2s_dacs"... Apr 14 18:29:07 volumio volumio[23735]: info: I2S DAC not set, start Auto-detection Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "volumiodiscovery"... Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** For more information see Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:07 volumio node[23735]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:07 volumio volumio[23735]: *** WARNING *** For more information see Apr 14 18:29:07 volumio node[23735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:07 volumio node[23735]: *** WARNING *** For more information see Apr 14 18:29:07 volumio node[23735]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:07 volumio node[23735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:07 volumio node[23735]: *** WARNING *** For more information see Apr 14 18:29:07 volumio volumio[23735]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 18:29:07 volumio volumio[23735]: info: Discovery: Started advertising with name: Volumio Apr 14 18:29:07 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "outputs"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "albumart"... Apr 14 18:29:07 volumio volumio[23735]: info: Plugin example_plugin is not enabled Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "inputs"... Apr 14 18:29:07 volumio volumio[23735]: info: Loading plugin "updater_comm"... Apr 14 18:29:08 volumio volumio-remote-updater[457]: [2024-04-14 18:29:08] [connect] Successful connection Apr 14 18:29:08 volumio volumio[23735]: info: Plugin mpdemulation is not enabled Apr 14 18:29:08 volumio volumio[23735]: info: Loading plugin "rest_api"... Apr 14 18:29:08 volumio volumio[23735]: info: Loading plugin "websocket"... Apr 14 18:29:08 volumio volumio[23735]: info: Loading i18n strings for locale ru Apr 14 18:29:08 volumio volumio[23735]: Updating browse sources language Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:08 volumio volumio[23735]: Forking 3 albumart workers Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::initPlayerControls Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: Express server listening on port 3000 Apr 14 18:29:08 volumio volumio[23735]: [Metrics] WebUI: 3s 897.21ms Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::resetVolumioState Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::getcurrentVolume Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:29:08 volumio volumio[23735]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 1 Apr 14 18:29:08 volumio volumio[23735]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 2 Apr 14 18:29:08 volumio volumio-remote-updater[457]: [2024-04-14 18:29:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713101348 101 Apr 14 18:29:08 volumio volumio[23735]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 14 18:29:08 volumio volumio[23735]: info: Reloading queue from file Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::setRepeat false single undefined Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::pushState Apr 14 18:29:08 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::setRandom null Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::pushState Apr 14 18:29:08 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:08 volumio volumio[23735]: info: Setting Device type: Raspberry PI Apr 14 18:29:08 volumio volumio[23735]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::pushState Apr 14 18:29:08 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::updateTrackBlock Apr 14 18:29:08 volumio volumio[23735]: info: CorePlayQueue::getTrackBlock Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:08 volumio volumio[23735]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:08 volumio volumio[23735]: info: CoreStateMachine::pushState Apr 14 18:29:08 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:08 volumio volumio[23735]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:09 volumio volumio[23735]: info: Discovery: adding 531e7a4d-078c-4395-b12d-7c9a334ec9ea Apr 14 18:29:09 volumio volumio[23735]: info: Discovery: Found device Volumio Apr 14 18:29:09 volumio volumio[23735]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:09 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:09 volumio volumio[23735]: Starting albumart workers Apr 14 18:29:09 volumio volumio[23735]: Starting albumart workers Apr 14 18:29:09 volumio volumio[23735]: Starting albumart workers Apr 14 18:29:10 volumio sudo[23766]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:11 volumio volumio[23735]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 4 Apr 14 18:29:11 volumio volumio[23735]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:11 volumio volumio[23735]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:11 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 18:29:11 volumio volumio[23735]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:29:11 volumio volumio[23735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:11 volumio volumio[23735]: info: Listing playlists Apr 14 18:29:11 volumio volumio[23735]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:29:11 volumio volumio[23735]: info: CoreStateMachine::getQueue Apr 14 18:29:11 volumio volumio[23735]: info: CorePlayQueue::getQueue Apr 14 18:29:11 volumio volumio[23735]: info: Cannot mount NAS Music-PC at system boot, trial number 2 ,retrying in 5 seconds Apr 14 18:29:16 volumio volumio[23735]: info: Cannot mount NAS Music-PC at system boot, trial number 3 ,retrying in 5 seconds Apr 14 18:29:16 volumio sudo[23880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:16 volumio sudo[23880]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:17 volumio sudo[23880]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:17 volumio sudo[23882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:17 volumio sudo[23882]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:17 volumio sudo[23882]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:17 volumio sudo[23886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 18:29:17 volumio sudo[23886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:17 volumio sudo[23886]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:17 volumio volumio[23735]: info: Upmpdcli Daemon Started Apr 14 18:29:21 volumio volumio[23735]: info: Preload queue cleared Apr 14 18:29:21 volumio volumio[23735]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:29:21 volumio volumio[23735]: info: CoreStateMachine::ClearQueue Apr 14 18:29:21 volumio volumio[23735]: info: CoreStateMachine::stop Apr 14 18:29:21 volumio volumio[23735]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:21 volumio volumio[23735]: info: CorePlayQueue::clearPlayQueue Apr 14 18:29:21 volumio volumio[23735]: info: CorePlayQueue::saveQueue Apr 14 18:29:21 volumio volumio[23735]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:29:21 volumio volumio[23735]: info: CoreStateMachine::addQueueItems Apr 14 18:29:21 volumio volumio[23735]: info: CorePlayQueue::addQueueItems Apr 14 18:29:21 volumio volumio[23735]: info: Preload queue cleared Apr 14 18:29:21 volumio volumio[23735]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:29:21 volumio volumio[23735]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:29:21 volumio volumio[23735]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:21 volumio volumio[23735]: TypeError: Cannot read property 'then' of undefined Apr 14 18:29:21 volumio volumio[23735]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 14 18:29:21 volumio volumio[23735]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 14 18:29:21 volumio volumio[23735]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 14 18:29:21 volumio volumio[23735]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 14 18:29:21 volumio volumio[23735]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 14 18:29:21 volumio volumio[23735]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 14 18:29:21 volumio volumio[23735]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 14 18:29:21 volumio volumio[23735]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Apr 14 18:29:21 volumio volumio[23735]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 14 18:29:21 volumio volumio[23735]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 14 18:29:21 volumio volumio[23735]: at Socket.emit (events.js:315:20) Apr 14 18:29:21 volumio volumio[23735]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 18:29:21 volumio volumio[23735]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 18:29:21 volumio volumio[23735]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:22 volumio sudo[23897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:28 Apr 14 18:29:22 volumio sudo[23897]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:22 volumio sudo[23897]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:22 volumio volumio-remote-updater[457]: [2024-04-14 18:29:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 18:29:22 volumio volumio-remote-updater[457]: [2024-04-14 18:29:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 18:29:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 18:29:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 18:29:22 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:22 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 18:29:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Apr 14 18:29:22 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:22 volumio systemd[1]: Stopped Volumio Backend Module. Apr 14 18:29:22 volumio systemd[1]: Started Volumio Backend Module. Apr 14 18:29:22 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:24 volumio volumio[23908]: info: ------------------------------------------- Apr 14 18:29:24 volumio volumio[23908]: info: ----- Volumio3 ---- Apr 14 18:29:24 volumio volumio[23908]: info: ------------------------------------------- Apr 14 18:29:24 volumio volumio[23908]: info: ----- System startup ---- Apr 14 18:29:24 volumio volumio[23908]: info: ------------------------------------------- Apr 14 18:29:25 volumio volumio[23908]: info: MYVOLUMIO Environment detected Apr 14 18:29:25 volumio volumio[23908]: info: Plugin folders cleanup Apr 14 18:29:25 volumio volumio[23908]: info: Scanning into folder /volumio/app/plugins/ Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category audio_interface Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category miscellanea Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category music_service Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category plugins.json Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category system_controller Apr 14 18:29:25 volumio volumio[23908]: info: Scanning category user_interface Apr 14 18:29:25 volumio volumio[23908]: info: Scanning into folder /data/plugins/ Apr 14 18:29:25 volumio volumio[23908]: info: Plugin folders cleanup completed Apr 14 18:29:25 volumio volumio[23908]: info: ------------------------------------------- Apr 14 18:29:25 volumio volumio[23908]: info: ----- Core plugins startup ---- Apr 14 18:29:25 volumio volumio[23908]: info: ------------------------------------------- Apr 14 18:29:25 volumio volumio[23908]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 18:29:25 volumio volumio[23908]: info: Adding plugin upnp to MyMusic Plugins Apr 14 18:29:25 volumio volumio[23908]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 18:29:25 volumio volumio[23908]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 18:29:25 volumio volumio[23908]: info: Loading plugins from folder /data/plugins/ Apr 14 18:29:25 volumio volumio[23908]: info: Loading plugin "system"... Apr 14 18:29:25 volumio volumio[23908]: info: Loading plugin "appearance"... Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "network"... Apr 14 18:29:26 volumio volumio[23908]: info: Refreshing Cached IP Addresses Apr 14 18:29:26 volumio sudo[23934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:26 volumio sudo[23934]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:26 volumio sudo[23934]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:26 volumio sudo[23936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "services"... Apr 14 18:29:26 volumio sudo[23936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "alsa_controller"... Apr 14 18:29:26 volumio sudo[23936]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:26 volumio sudo[23939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 18:29:26 volumio sudo[23939]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:26 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "wizard"... Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "networkfs"... Apr 14 18:29:26 volumio volumio[23908]: info: Cannot mount NAS Music-PC at system boot, trial number 1 ,retrying in 5 seconds Apr 14 18:29:26 volumio volumio[23908]: info: Starting Udev Watcher for removable devices Apr 14 18:29:26 volumio volumio[23908]: info: Ignoring mount for partition: boot Apr 14 18:29:26 volumio volumio[23908]: info: Ignoring mount for partition: volumio Apr 14 18:29:26 volumio volumio[23908]: info: Ignoring mount for partition: volumio_data Apr 14 18:29:26 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "volumio_command_line_client"... Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "upnp"... Apr 14 18:29:26 volumio volumio[23908]: info: [1713101366476] Starting Upmpd Daemon Apr 14 18:29:26 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "my_music"... Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "mpd"... Apr 14 18:29:26 volumio volumio[23908]: info: Loading plugin "upnp_browser"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "alarm-clock"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "airplay_emulation"... Apr 14 18:29:27 volumio volumio[23908]: info: Starting Shairport Sync Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "last_100"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "webradio"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "i2s_dacs"... Apr 14 18:29:27 volumio volumio[23908]: info: I2S DAC not set, start Auto-detection Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "volumiodiscovery"... Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:27 volumio node[23908]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:27 volumio node[23908]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** For more information see Apr 14 18:29:27 volumio node[23908]: *** WARNING *** For more information see Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:27 volumio node[23908]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:27 volumio node[23908]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:27 volumio volumio[23908]: *** WARNING *** For more information see Apr 14 18:29:27 volumio node[23908]: *** WARNING *** For more information see Apr 14 18:29:27 volumio volumio[23908]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 18:29:27 volumio volumio[23908]: info: Discovery: Started advertising with name: Volumio Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "outputs"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "albumart"... Apr 14 18:29:27 volumio volumio[23908]: info: Plugin example_plugin is not enabled Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "inputs"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "updater_comm"... Apr 14 18:29:27 volumio volumio-remote-updater[457]: [2024-04-14 18:29:27] [connect] Successful connection Apr 14 18:29:27 volumio volumio[23908]: info: Plugin mpdemulation is not enabled Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "rest_api"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading plugin "websocket"... Apr 14 18:29:27 volumio volumio[23908]: info: Loading i18n strings for locale ru Apr 14 18:29:27 volumio volumio[23908]: Updating browse sources language Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:27 volumio volumio[23908]: Forking 3 albumart workers Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::initPlayerControls Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:27 volumio volumio[23908]: Express server listening on port 3000 Apr 14 18:29:27 volumio volumio[23908]: [Metrics] WebUI: 3s 960.17ms Apr 14 18:29:27 volumio volumio[23908]: info: CoreStateMachine::resetVolumioState Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::getcurrentVolume Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:29:28 volumio volumio[23908]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 1 Apr 14 18:29:28 volumio volumio[23908]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 2 Apr 14 18:29:28 volumio volumio-remote-updater[457]: [2024-04-14 18:29:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713101367 101 Apr 14 18:29:28 volumio volumio[23908]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 14 18:29:28 volumio volumio[23908]: info: Reloading queue from file Apr 14 18:29:28 volumio volumio[23908]: info: Setting Device type: Raspberry PI Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::setRepeat false single undefined Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::pushState Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::setRandom null Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::pushState Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:28 volumio volumio[23908]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::pushState Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::updateTrackBlock Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrackBlock Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:28 volumio volumio[23908]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:28 volumio volumio[23908]: info: CoreStateMachine::pushState Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:28 volumio volumio[23908]: Starting albumart workers Apr 14 18:29:28 volumio volumio[23908]: info: Discovery: adding 531e7a4d-078c-4395-b12d-7c9a334ec9ea Apr 14 18:29:28 volumio volumio[23908]: info: Discovery: Found device Volumio Apr 14 18:29:28 volumio volumio[23908]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:28 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:28 volumio volumio[23908]: Starting albumart workers Apr 14 18:29:28 volumio volumio[23908]: Starting albumart workers Apr 14 18:29:29 volumio volumio[23908]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 4 Apr 14 18:29:29 volumio volumio[23908]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:29 volumio volumio[23908]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:30 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 18:29:30 volumio volumio[23908]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:29:30 volumio volumio[23908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:30 volumio volumio[23908]: info: Listing playlists Apr 14 18:29:30 volumio volumio[23908]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:29:30 volumio volumio[23908]: info: CoreStateMachine::getQueue Apr 14 18:29:30 volumio volumio[23908]: info: CorePlayQueue::getQueue Apr 14 18:29:30 volumio sudo[23939]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:31 volumio volumio[23908]: info: Cannot mount NAS Music-PC at system boot, trial number 2 ,retrying in 5 seconds Apr 14 18:29:36 volumio volumio[23908]: info: Cannot mount NAS Music-PC at system boot, trial number 3 ,retrying in 5 seconds Apr 14 18:29:36 volumio sudo[24014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:36 volumio sudo[24014]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:36 volumio sudo[24014]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:36 volumio sudo[24016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:36 volumio sudo[24016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:36 volumio sudo[24016]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:36 volumio sudo[24020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 18:29:36 volumio sudo[24020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:36 volumio sudo[24020]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:36 volumio volumio[23908]: info: Upmpdcli Daemon Started Apr 14 18:29:40 volumio volumio[23908]: info: Preload queue cleared Apr 14 18:29:40 volumio volumio[23908]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:29:40 volumio volumio[23908]: info: CoreStateMachine::ClearQueue Apr 14 18:29:40 volumio volumio[23908]: info: CoreStateMachine::stop Apr 14 18:29:40 volumio volumio[23908]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:40 volumio volumio[23908]: info: CorePlayQueue::clearPlayQueue Apr 14 18:29:40 volumio volumio[23908]: info: CorePlayQueue::saveQueue Apr 14 18:29:40 volumio volumio[23908]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:29:40 volumio volumio[23908]: info: CoreStateMachine::addQueueItems Apr 14 18:29:40 volumio volumio[23908]: info: CorePlayQueue::addQueueItems Apr 14 18:29:40 volumio volumio[23908]: info: Preload queue cleared Apr 14 18:29:40 volumio volumio[23908]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:29:40 volumio volumio[23908]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:29:40 volumio volumio[23908]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:40 volumio volumio[23908]: TypeError: Cannot read property 'then' of undefined Apr 14 18:29:40 volumio volumio[23908]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 14 18:29:40 volumio volumio[23908]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 14 18:29:40 volumio volumio[23908]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 14 18:29:40 volumio volumio[23908]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 14 18:29:40 volumio volumio[23908]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 14 18:29:40 volumio volumio[23908]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 14 18:29:40 volumio volumio[23908]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 14 18:29:40 volumio volumio[23908]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 14 18:29:40 volumio volumio[23908]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 14 18:29:40 volumio volumio[23908]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 14 18:29:40 volumio volumio[23908]: at Socket.emit (events.js:315:20) Apr 14 18:29:40 volumio volumio[23908]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 18:29:40 volumio volumio[23908]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 18:29:40 volumio volumio[23908]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:40 volumio sudo[24031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:28 Apr 14 18:29:40 volumio sudo[24031]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:40 volumio sudo[24031]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:41 volumio volumio-remote-updater[457]: [2024-04-14 18:29:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 18:29:41 volumio volumio-remote-updater[457]: [2024-04-14 18:29:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 18:29:41 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 18:29:41 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 18:29:41 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:41 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:41 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 18:29:41 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Apr 14 18:29:41 volumio systemd[1]: Stopped Volumio Backend Module. Apr 14 18:29:41 volumio systemd[1]: Started Volumio Backend Module. Apr 14 18:29:41 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:41 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:43 volumio volumio[24042]: info: ------------------------------------------- Apr 14 18:29:43 volumio volumio[24042]: info: ----- Volumio3 ---- Apr 14 18:29:43 volumio volumio[24042]: info: ------------------------------------------- Apr 14 18:29:43 volumio volumio[24042]: info: ----- System startup ---- Apr 14 18:29:43 volumio volumio[24042]: info: ------------------------------------------- Apr 14 18:29:43 volumio volumio[24042]: info: MYVOLUMIO Environment detected Apr 14 18:29:43 volumio volumio[24042]: info: Plugin folders cleanup Apr 14 18:29:43 volumio volumio[24042]: info: Scanning into folder /volumio/app/plugins/ Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category audio_interface Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category miscellanea Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category music_service Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category plugins.json Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category system_controller Apr 14 18:29:43 volumio volumio[24042]: info: Scanning category user_interface Apr 14 18:29:43 volumio volumio[24042]: info: Scanning into folder /data/plugins/ Apr 14 18:29:43 volumio volumio[24042]: info: Plugin folders cleanup completed Apr 14 18:29:43 volumio volumio[24042]: info: ------------------------------------------- Apr 14 18:29:43 volumio volumio[24042]: info: ----- Core plugins startup ---- Apr 14 18:29:43 volumio volumio[24042]: info: ------------------------------------------- Apr 14 18:29:43 volumio volumio[24042]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 18:29:43 volumio volumio[24042]: info: Adding plugin upnp to MyMusic Plugins Apr 14 18:29:43 volumio volumio[24042]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 18:29:43 volumio volumio[24042]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 18:29:43 volumio volumio[24042]: info: Loading plugins from folder /data/plugins/ Apr 14 18:29:43 volumio volumio[24042]: info: Loading plugin "system"... Apr 14 18:29:43 volumio volumio[24042]: info: Loading plugin "appearance"... Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "network"... Apr 14 18:29:44 volumio volumio[24042]: info: Refreshing Cached IP Addresses Apr 14 18:29:44 volumio sudo[24069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:44 volumio sudo[24069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:44 volumio sudo[24069]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:44 volumio sudo[24071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:44 volumio sudo[24071]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:44 volumio sudo[24071]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "services"... Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "alsa_controller"... Apr 14 18:29:44 volumio sudo[24074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 18:29:44 volumio sudo[24074]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:44 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "wizard"... Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "networkfs"... Apr 14 18:29:44 volumio volumio[24042]: info: Cannot mount NAS Music-PC at system boot, trial number 1 ,retrying in 5 seconds Apr 14 18:29:44 volumio volumio[24042]: info: Starting Udev Watcher for removable devices Apr 14 18:29:44 volumio volumio[24042]: info: Ignoring mount for partition: boot Apr 14 18:29:44 volumio volumio[24042]: info: Ignoring mount for partition: volumio Apr 14 18:29:44 volumio volumio[24042]: info: Ignoring mount for partition: volumio_data Apr 14 18:29:44 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "volumio_command_line_client"... Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "upnp"... Apr 14 18:29:44 volumio volumio[24042]: info: [1713101384973] Starting Upmpd Daemon Apr 14 18:29:44 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "my_music"... Apr 14 18:29:44 volumio volumio[24042]: info: Loading plugin "mpd"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "upnp_browser"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "alarm-clock"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "airplay_emulation"... Apr 14 18:29:45 volumio volumio[24042]: info: Starting Shairport Sync Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "last_100"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "webradio"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "i2s_dacs"... Apr 14 18:29:45 volumio volumio[24042]: info: I2S DAC not set, start Auto-detection Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "volumiodiscovery"... Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:45 volumio node[24042]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:45 volumio node[24042]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** For more information see Apr 14 18:29:45 volumio node[24042]: *** WARNING *** For more information see Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:45 volumio node[24042]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:45 volumio node[24042]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:29:45 volumio volumio[24042]: *** WARNING *** For more information see Apr 14 18:29:45 volumio node[24042]: *** WARNING *** For more information see Apr 14 18:29:45 volumio volumio[24042]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 18:29:45 volumio volumio[24042]: info: Discovery: Started advertising with name: Volumio Apr 14 18:29:45 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "outputs"... Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "albumart"... Apr 14 18:29:45 volumio volumio[24042]: info: Plugin example_plugin is not enabled Apr 14 18:29:45 volumio volumio[24042]: info: Loading plugin "inputs"... Apr 14 18:29:46 volumio volumio[24042]: info: Loading plugin "updater_comm"... Apr 14 18:29:46 volumio volumio-remote-updater[457]: [2024-04-14 18:29:46] [connect] Successful connection Apr 14 18:29:46 volumio volumio[24042]: info: Plugin mpdemulation is not enabled Apr 14 18:29:46 volumio volumio[24042]: info: Loading plugin "rest_api"... Apr 14 18:29:46 volumio volumio[24042]: info: Loading plugin "websocket"... Apr 14 18:29:46 volumio volumio[24042]: info: Loading i18n strings for locale ru Apr 14 18:29:46 volumio volumio[24042]: Updating browse sources language Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:46 volumio volumio[24042]: Forking 3 albumart workers Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::initPlayerControls Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: Express server listening on port 3000 Apr 14 18:29:46 volumio volumio[24042]: [Metrics] WebUI: 3s 956.95ms Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::resetVolumioState Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::getcurrentVolume Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:29:46 volumio volumio[24042]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 1 Apr 14 18:29:46 volumio volumio[24042]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 2 Apr 14 18:29:46 volumio volumio-remote-updater[457]: [2024-04-14 18:29:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713101386 101 Apr 14 18:29:46 volumio volumio[24042]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 14 18:29:46 volumio volumio[24042]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::pushState Apr 14 18:29:46 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::updateTrackBlock Apr 14 18:29:46 volumio volumio[24042]: info: CorePlayQueue::getTrackBlock Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:29:46 volumio volumio[24042]: info: Reloading queue from file Apr 14 18:29:46 volumio volumio[24042]: info: Setting Device type: Raspberry PI Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::setRepeat false single undefined Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::pushState Apr 14 18:29:46 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::setRandom null Apr 14 18:29:46 volumio volumio[24042]: info: CoreStateMachine::pushState Apr 14 18:29:46 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:46 volumio volumio[24042]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:47 volumio volumio[24042]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:29:47 volumio volumio[24042]: info: CoreStateMachine::pushState Apr 14 18:29:47 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:47 volumio volumio[24042]: info: CoreCommandRouter::volumioPushState Apr 14 18:29:47 volumio volumio[24042]: Starting albumart workers Apr 14 18:29:47 volumio volumio[24042]: Starting albumart workers Apr 14 18:29:47 volumio volumio[24042]: info: Discovery: adding 531e7a4d-078c-4395-b12d-7c9a334ec9ea Apr 14 18:29:47 volumio volumio[24042]: info: Discovery: Found device Volumio Apr 14 18:29:47 volumio volumio[24042]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:47 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:47 volumio volumio[24042]: Starting albumart workers Apr 14 18:29:48 volumio sudo[24074]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:49 volumio volumio[24042]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 4 Apr 14 18:29:49 volumio volumio[24042]: info: CoreCommandRouter::volumioGetState Apr 14 18:29:49 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:49 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 18:29:49 volumio volumio[24042]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:29:49 volumio volumio[24042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:29:49 volumio volumio[24042]: info: Listing playlists Apr 14 18:29:49 volumio volumio[24042]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:29:49 volumio volumio[24042]: info: CoreStateMachine::getQueue Apr 14 18:29:49 volumio volumio[24042]: info: CorePlayQueue::getQueue Apr 14 18:29:49 volumio volumio[24042]: info: Cannot mount NAS Music-PC at system boot, trial number 2 ,retrying in 5 seconds Apr 14 18:29:50 volumio volumio[24042]: info: CoreCommandRouter::volumioPlay Apr 14 18:29:50 volumio volumio[24042]: info: CoreStateMachine::play index undefined Apr 14 18:29:50 volumio volumio[24042]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:50 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:53 volumio volumio[24042]: info: CoreCommandRouter::volumioPlay Apr 14 18:29:53 volumio volumio[24042]: info: CoreStateMachine::play index undefined Apr 14 18:29:53 volumio volumio[24042]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:53 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:53 volumio volumio[24042]: info: CoreCommandRouter::volumioPlay Apr 14 18:29:53 volumio volumio[24042]: info: CoreStateMachine::play index undefined Apr 14 18:29:53 volumio volumio[24042]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:53 volumio volumio[24042]: info: CorePlayQueue::getTrack 0 Apr 14 18:29:54 volumio volumio[24042]: info: Cannot mount NAS Music-PC at system boot, trial number 3 ,retrying in 5 seconds Apr 14 18:29:55 volumio sudo[24149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:29:55 volumio sudo[24149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:55 volumio sudo[24149]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:55 volumio sudo[24151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:29:55 volumio sudo[24151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:55 volumio sudo[24151]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:55 volumio sudo[24155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 18:29:55 volumio sudo[24155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:55 volumio sudo[24155]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:55 volumio volumio[24042]: info: Upmpdcli Daemon Started Apr 14 18:29:56 volumio volumio[24042]: info: Preload queue cleared Apr 14 18:29:56 volumio volumio[24042]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:29:56 volumio volumio[24042]: info: CoreStateMachine::ClearQueue Apr 14 18:29:56 volumio volumio[24042]: info: CoreStateMachine::stop Apr 14 18:29:56 volumio volumio[24042]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:29:56 volumio volumio[24042]: info: CorePlayQueue::clearPlayQueue Apr 14 18:29:56 volumio volumio[24042]: info: CorePlayQueue::saveQueue Apr 14 18:29:56 volumio volumio[24042]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:29:56 volumio volumio[24042]: info: CoreStateMachine::addQueueItems Apr 14 18:29:56 volumio volumio[24042]: info: CorePlayQueue::addQueueItems Apr 14 18:29:56 volumio volumio[24042]: info: Preload queue cleared Apr 14 18:29:56 volumio volumio[24042]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:29:56 volumio volumio[24042]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:29:56 volumio volumio[24042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:56 volumio volumio[24042]: TypeError: Cannot read property 'then' of undefined Apr 14 18:29:56 volumio volumio[24042]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 14 18:29:56 volumio volumio[24042]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 14 18:29:56 volumio volumio[24042]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 14 18:29:56 volumio volumio[24042]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 14 18:29:56 volumio volumio[24042]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 14 18:29:56 volumio volumio[24042]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 14 18:29:56 volumio volumio[24042]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 14 18:29:56 volumio volumio[24042]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 14 18:29:56 volumio volumio[24042]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 14 18:29:56 volumio volumio[24042]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 14 18:29:56 volumio volumio[24042]: at Socket.emit (events.js:315:20) Apr 14 18:29:56 volumio volumio[24042]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 18:29:56 volumio volumio[24042]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 18:29:56 volumio volumio[24042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:29:56 volumio sudo[24166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:28 Apr 14 18:29:56 volumio sudo[24166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:29:57 volumio sudo[24166]: pam_unix(sudo:session): session closed for user root Apr 14 18:29:57 volumio volumio-remote-updater[457]: [2024-04-14 18:29:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 18:29:57 volumio volumio-remote-updater[457]: [2024-04-14 18:29:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 18:29:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 18:29:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 18:29:57 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:57 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 18:29:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Apr 14 18:29:57 volumio systemd[1]: Stopped Volumio Backend Module. Apr 14 18:29:57 volumio systemd[1]: Started Volumio Backend Module. Apr 14 18:29:57 volumio systemd[1]: Started dynamicswap service. Apr 14 18:29:57 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:29:58 volumio volumio[24177]: info: ------------------------------------------- Apr 14 18:29:59 volumio volumio[24177]: info: ----- Volumio3 ---- Apr 14 18:29:59 volumio volumio[24177]: info: ------------------------------------------- Apr 14 18:29:59 volumio volumio[24177]: info: ----- System startup ---- Apr 14 18:29:59 volumio volumio[24177]: info: ------------------------------------------- Apr 14 18:29:59 volumio volumio[24177]: info: MYVOLUMIO Environment detected Apr 14 18:29:59 volumio volumio[24177]: info: Plugin folders cleanup Apr 14 18:29:59 volumio volumio[24177]: info: Scanning into folder /volumio/app/plugins/ Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category audio_interface Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category miscellanea Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category music_service Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category plugins.json Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category system_controller Apr 14 18:29:59 volumio volumio[24177]: info: Scanning category user_interface Apr 14 18:29:59 volumio volumio[24177]: info: Scanning into folder /data/plugins/ Apr 14 18:29:59 volumio volumio[24177]: info: Plugin folders cleanup completed Apr 14 18:29:59 volumio volumio[24177]: info: ------------------------------------------- Apr 14 18:29:59 volumio volumio[24177]: info: ----- Core plugins startup ---- Apr 14 18:29:59 volumio volumio[24177]: info: ------------------------------------------- Apr 14 18:29:59 volumio volumio[24177]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 18:29:59 volumio volumio[24177]: info: Adding plugin upnp to MyMusic Plugins Apr 14 18:29:59 volumio volumio[24177]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 18:29:59 volumio volumio[24177]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 18:29:59 volumio volumio[24177]: info: Loading plugins from folder /data/plugins/ Apr 14 18:29:59 volumio volumio[24177]: info: Loading plugin "system"... Apr 14 18:29:59 volumio volumio[24177]: info: Loading plugin "appearance"... Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "network"... Apr 14 18:30:00 volumio volumio[24177]: info: Refreshing Cached IP Addresses Apr 14 18:30:00 volumio sudo[24204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:30:00 volumio sudo[24204]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:00 volumio sudo[24204]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:00 volumio sudo[24206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "services"... Apr 14 18:30:00 volumio sudo[24206]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "alsa_controller"... Apr 14 18:30:00 volumio sudo[24206]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:00 volumio sudo[24209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 18:30:00 volumio sudo[24209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:00 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "wizard"... Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "networkfs"... Apr 14 18:30:00 volumio volumio[24177]: info: Cannot mount NAS Music-PC at system boot, trial number 1 ,retrying in 5 seconds Apr 14 18:30:00 volumio volumio[24177]: info: Starting Udev Watcher for removable devices Apr 14 18:30:00 volumio volumio[24177]: info: Ignoring mount for partition: boot Apr 14 18:30:00 volumio volumio[24177]: info: Ignoring mount for partition: volumio Apr 14 18:30:00 volumio volumio[24177]: info: Ignoring mount for partition: volumio_data Apr 14 18:30:00 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "volumio_command_line_client"... Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "upnp"... Apr 14 18:30:00 volumio volumio[24177]: info: [1713101400928] Starting Upmpd Daemon Apr 14 18:30:00 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "my_music"... Apr 14 18:30:00 volumio volumio[24177]: info: Loading plugin "mpd"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "upnp_browser"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "alarm-clock"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "airplay_emulation"... Apr 14 18:30:01 volumio volumio[24177]: info: Starting Shairport Sync Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "last_100"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "webradio"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "i2s_dacs"... Apr 14 18:30:01 volumio volumio[24177]: info: I2S DAC not set, start Auto-detection Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "volumiodiscovery"... Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** For more information see Apr 14 18:30:01 volumio node[24177]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:01 volumio volumio[24177]: *** WARNING *** For more information see Apr 14 18:30:01 volumio node[24177]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:01 volumio node[24177]: *** WARNING *** For more information see Apr 14 18:30:01 volumio node[24177]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:01 volumio node[24177]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:01 volumio node[24177]: *** WARNING *** For more information see Apr 14 18:30:01 volumio volumio[24177]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 18:30:01 volumio volumio[24177]: info: Discovery: Started advertising with name: Volumio Apr 14 18:30:01 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "outputs"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "albumart"... Apr 14 18:30:01 volumio volumio[24177]: info: Plugin example_plugin is not enabled Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "inputs"... Apr 14 18:30:01 volumio volumio[24177]: info: Loading plugin "updater_comm"... Apr 14 18:30:02 volumio volumio[24177]: info: Plugin mpdemulation is not enabled Apr 14 18:30:02 volumio volumio[24177]: info: Loading plugin "rest_api"... Apr 14 18:30:02 volumio volumio[24177]: info: Loading plugin "websocket"... Apr 14 18:30:02 volumio volumio[24177]: info: Loading i18n strings for locale ru Apr 14 18:30:02 volumio volumio-remote-updater[457]: [2024-04-14 18:30:02] [connect] Successful connection Apr 14 18:30:02 volumio volumio[24177]: Updating browse sources language Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:30:02 volumio volumio[24177]: Forking 3 albumart workers Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::initPlayerControls Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: Express server listening on port 3000 Apr 14 18:30:02 volumio volumio[24177]: [Metrics] WebUI: 3s 900.59ms Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::resetVolumioState Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::getcurrentVolume Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:02 volumio volumio[24177]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 1 Apr 14 18:30:02 volumio volumio[24177]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 2 Apr 14 18:30:02 volumio volumio-remote-updater[457]: [2024-04-14 18:30:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713101402 101 Apr 14 18:30:02 volumio volumio[24177]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 14 18:30:02 volumio volumio[24177]: info: Reloading queue from file Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::setRepeat false single undefined Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::pushState Apr 14 18:30:02 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::setRandom null Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::pushState Apr 14 18:30:02 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:02 volumio volumio[24177]: info: Setting Device type: Raspberry PI Apr 14 18:30:02 volumio volumio[24177]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::pushState Apr 14 18:30:02 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::updateTrackBlock Apr 14 18:30:02 volumio volumio[24177]: info: CorePlayQueue::getTrackBlock Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:02 volumio volumio[24177]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:02 volumio volumio[24177]: info: CoreStateMachine::pushState Apr 14 18:30:02 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:02 volumio volumio[24177]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:03 volumio volumio[24177]: info: Discovery: adding 531e7a4d-078c-4395-b12d-7c9a334ec9ea Apr 14 18:30:03 volumio volumio[24177]: info: Discovery: Found device Volumio Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::volumioGetState Apr 14 18:30:03 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:03 volumio volumio[24177]: Starting albumart workers Apr 14 18:30:03 volumio volumio[24177]: Starting albumart workers Apr 14 18:30:03 volumio volumio[24177]: Starting albumart workers Apr 14 18:30:03 volumio volumio[24177]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 4 Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::volumioGetState Apr 14 18:30:03 volumio volumio[24177]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:30:03 volumio volumio[24177]: info: Listing playlists Apr 14 18:30:03 volumio volumio[24177]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:30:03 volumio volumio[24177]: info: CoreStateMachine::getQueue Apr 14 18:30:03 volumio volumio[24177]: info: CorePlayQueue::getQueue Apr 14 18:30:04 volumio sudo[24209]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:05 volumio volumio[24177]: info: Cannot mount NAS Music-PC at system boot, trial number 2 ,retrying in 5 seconds Apr 14 18:30:07 volumio volumio[24177]: info: Preload queue cleared Apr 14 18:30:07 volumio volumio[24177]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:30:07 volumio volumio[24177]: info: CoreStateMachine::ClearQueue Apr 14 18:30:07 volumio volumio[24177]: info: CoreStateMachine::stop Apr 14 18:30:07 volumio volumio[24177]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:30:07 volumio volumio[24177]: info: CorePlayQueue::clearPlayQueue Apr 14 18:30:07 volumio volumio[24177]: info: CorePlayQueue::saveQueue Apr 14 18:30:07 volumio volumio[24177]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:30:07 volumio volumio[24177]: info: CoreStateMachine::addQueueItems Apr 14 18:30:07 volumio volumio[24177]: info: CorePlayQueue::addQueueItems Apr 14 18:30:07 volumio volumio[24177]: info: Preload queue cleared Apr 14 18:30:07 volumio volumio[24177]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:30:07 volumio volumio[24177]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:30:07 volumio volumio[24177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:30:07 volumio volumio[24177]: TypeError: Cannot read property 'then' of undefined Apr 14 18:30:07 volumio volumio[24177]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 14 18:30:07 volumio volumio[24177]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 14 18:30:07 volumio volumio[24177]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 14 18:30:07 volumio volumio[24177]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 14 18:30:07 volumio volumio[24177]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 14 18:30:07 volumio volumio[24177]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 14 18:30:07 volumio volumio[24177]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 14 18:30:07 volumio volumio[24177]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 14 18:30:07 volumio volumio[24177]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 14 18:30:07 volumio volumio[24177]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 14 18:30:07 volumio volumio[24177]: at Socket.emit (events.js:315:20) Apr 14 18:30:07 volumio volumio[24177]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 18:30:07 volumio volumio[24177]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 18:30:07 volumio volumio[24177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:30:07 volumio sudo[24291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:29 Apr 14 18:30:07 volumio sudo[24291]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:07 volumio sudo[24291]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:07 volumio volumio-remote-updater[457]: [2024-04-14 18:30:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 18:30:07 volumio volumio-remote-updater[457]: [2024-04-14 18:30:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 18:30:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 18:30:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 18:30:08 volumio systemd[1]: Started dynamicswap service. Apr 14 18:30:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:30:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 18:30:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Apr 14 18:30:08 volumio systemd[1]: Started dynamicswap service. Apr 14 18:30:08 volumio systemd[1]: Stopped Volumio Backend Module. Apr 14 18:30:08 volumio systemd[1]: Started Volumio Backend Module. Apr 14 18:30:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 14 18:30:10 volumio volumio[24303]: info: ------------------------------------------- Apr 14 18:30:10 volumio volumio[24303]: info: ----- Volumio3 ---- Apr 14 18:30:10 volumio volumio[24303]: info: ------------------------------------------- Apr 14 18:30:10 volumio volumio[24303]: info: ----- System startup ---- Apr 14 18:30:10 volumio volumio[24303]: info: ------------------------------------------- Apr 14 18:30:10 volumio volumio[24303]: info: MYVOLUMIO Environment detected Apr 14 18:30:10 volumio volumio[24303]: info: Plugin folders cleanup Apr 14 18:30:10 volumio volumio[24303]: info: Scanning into folder /volumio/app/plugins/ Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category audio_interface Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category miscellanea Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category music_service Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category plugins.json Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category system_controller Apr 14 18:30:10 volumio volumio[24303]: info: Scanning category user_interface Apr 14 18:30:10 volumio volumio[24303]: info: Scanning into folder /data/plugins/ Apr 14 18:30:10 volumio volumio[24303]: info: Plugin folders cleanup completed Apr 14 18:30:10 volumio volumio[24303]: info: ------------------------------------------- Apr 14 18:30:10 volumio volumio[24303]: info: ----- Core plugins startup ---- Apr 14 18:30:10 volumio volumio[24303]: info: ------------------------------------------- Apr 14 18:30:10 volumio volumio[24303]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 18:30:10 volumio volumio[24303]: info: Adding plugin upnp to MyMusic Plugins Apr 14 18:30:10 volumio volumio[24303]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 18:30:10 volumio volumio[24303]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 18:30:10 volumio volumio[24303]: info: Loading plugins from folder /data/plugins/ Apr 14 18:30:10 volumio volumio[24303]: info: Loading plugin "system"... Apr 14 18:30:10 volumio volumio[24303]: info: Loading plugin "appearance"... Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "network"... Apr 14 18:30:11 volumio volumio[24303]: info: Refreshing Cached IP Addresses Apr 14 18:30:11 volumio sudo[24368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:30:11 volumio sudo[24368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:11 volumio sudo[24368]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:11 volumio sudo[24370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "services"... Apr 14 18:30:11 volumio sudo[24370]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "alsa_controller"... Apr 14 18:30:11 volumio sudo[24370]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:11 volumio sudo[24373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 18:30:11 volumio sudo[24373]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:11 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "wizard"... Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "networkfs"... Apr 14 18:30:11 volumio volumio[24303]: info: Cannot mount NAS Music-PC at system boot, trial number 1 ,retrying in 5 seconds Apr 14 18:30:11 volumio volumio[24303]: info: Starting Udev Watcher for removable devices Apr 14 18:30:11 volumio volumio[24303]: info: Ignoring mount for partition: boot Apr 14 18:30:11 volumio volumio[24303]: info: Ignoring mount for partition: volumio Apr 14 18:30:11 volumio volumio[24303]: info: Ignoring mount for partition: volumio_data Apr 14 18:30:11 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "volumio_command_line_client"... Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "upnp"... Apr 14 18:30:11 volumio volumio[24303]: info: [1713101411977] Starting Upmpd Daemon Apr 14 18:30:11 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "my_music"... Apr 14 18:30:11 volumio volumio[24303]: info: Loading plugin "mpd"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "upnp_browser"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "alarm-clock"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "airplay_emulation"... Apr 14 18:30:12 volumio volumio[24303]: info: Starting Shairport Sync Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "last_100"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "webradio"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "i2s_dacs"... Apr 14 18:30:12 volumio volumio[24303]: info: I2S DAC not set, start Auto-detection Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "volumiodiscovery"... Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** For more information see Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:12 volumio node[24303]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:12 volumio volumio[24303]: *** WARNING *** For more information see Apr 14 18:30:12 volumio node[24303]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:12 volumio node[24303]: *** WARNING *** For more information see Apr 14 18:30:12 volumio node[24303]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 18:30:12 volumio node[24303]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 18:30:12 volumio node[24303]: *** WARNING *** For more information see Apr 14 18:30:12 volumio volumio[24303]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 18:30:12 volumio volumio[24303]: info: Discovery: Started advertising with name: Volumio Apr 14 18:30:12 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "outputs"... Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "albumart"... Apr 14 18:30:12 volumio volumio-remote-updater[457]: [2024-04-14 18:30:12] [connect] Successful connection Apr 14 18:30:12 volumio volumio[24303]: info: Plugin example_plugin is not enabled Apr 14 18:30:12 volumio volumio[24303]: info: Loading plugin "inputs"... Apr 14 18:30:13 volumio volumio[24303]: info: Loading plugin "updater_comm"... Apr 14 18:30:13 volumio volumio[24303]: info: Plugin mpdemulation is not enabled Apr 14 18:30:13 volumio volumio[24303]: info: Loading plugin "rest_api"... Apr 14 18:30:13 volumio volumio[24303]: info: Loading plugin "websocket"... Apr 14 18:30:13 volumio volumio[24303]: info: Loading i18n strings for locale ru Apr 14 18:30:13 volumio volumio[24303]: Updating browse sources language Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:30:13 volumio volumio[24303]: Forking 3 albumart workers Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::initPlayerControls Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: Express server listening on port 3000 Apr 14 18:30:13 volumio volumio[24303]: [Metrics] WebUI: 3s 930.87ms Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::resetVolumioState Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::getcurrentVolume Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:13 volumio volumio[24303]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 1 Apr 14 18:30:13 volumio volumio[24303]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 2 Apr 14 18:30:13 volumio volumio-remote-updater[457]: [2024-04-14 18:30:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713101412 101 Apr 14 18:30:13 volumio volumio[24303]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 14 18:30:13 volumio volumio[24303]: info: Reloading queue from file Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::setRepeat false single undefined Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:13 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::setRandom null Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:13 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:13 volumio volumio[24303]: info: Setting Device type: Raspberry PI Apr 14 18:30:13 volumio volumio[24303]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:13 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:13 volumio volumio[24303]: info: CoreStateMachine::updateTrackBlock Apr 14 18:30:13 volumio volumio[24303]: info: CorePlayQueue::getTrackBlock Apr 14 18:30:13 volumio volumio[24303]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:14 volumio volumio[24303]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:14 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:14 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:14 volumio volumio[24303]: info: Discovery: adding 531e7a4d-078c-4395-b12d-7c9a334ec9ea Apr 14 18:30:14 volumio volumio[24303]: info: Discovery: Found device Volumio Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::volumioGetState Apr 14 18:30:14 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:14 volumio volumio[24303]: Starting albumart workers Apr 14 18:30:14 volumio volumio[24303]: Starting albumart workers Apr 14 18:30:14 volumio volumio[24303]: Starting albumart workers Apr 14 18:30:14 volumio volumio[24303]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 OPR/108.0.0.0 Total Clients: 4 Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::volumioGetState Apr 14 18:30:14 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 18:30:14 volumio volumio[24303]: info: Listing playlists Apr 14 18:30:14 volumio volumio[24303]: info: CoreCommandRouter::volumioGetQueue Apr 14 18:30:14 volumio volumio[24303]: info: CoreStateMachine::getQueue Apr 14 18:30:14 volumio volumio[24303]: info: CorePlayQueue::getQueue Apr 14 18:30:15 volumio sudo[24373]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:16 volumio volumio[24303]: info: Cannot mount NAS Music-PC at system boot, trial number 2 ,retrying in 5 seconds Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 18:30:19 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 18:30:21 volumio volumio[24303]: info: Cannot mount NAS Music-PC at system boot, trial number 3 ,retrying in 5 seconds Apr 14 18:30:22 volumio sudo[24451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 18:30:22 volumio sudo[24451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:22 volumio sudo[24451]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:22 volumio sudo[24453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 18:30:22 volumio sudo[24453]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:22 volumio sudo[24453]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:22 volumio sudo[24457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 18:30:22 volumio sudo[24457]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:22 volumio sudo[24457]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:22 volumio volumio[24303]: info: Upmpdcli Daemon Started Apr 14 18:30:26 volumio volumio[24303]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 18:30:26 volumio volumio[24303]: info: Found match in Cards Database: setting mixer PCM for card Headphones Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 18:30:26 volumio volumio[24303]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 18:30:26 volumio volumio[24303]: info: Disabling external Volume Control Apr 14 18:30:26 volumio volumio[24303]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:30:26 volumio volumio[24303]: info: Preparing to generate the ALSA configuration file Apr 14 18:30:27 volumio volumio[24303]: info: Cannot mount NAS Music-PC at system boot, trial number 4 ,retrying in 5 seconds Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 18:30:27 volumio volumio[24303]: info: Asound.conf file unchanged, so no further update is needed Apr 14 18:30:27 volumio volumio[24303]: info: Output device has changed, restarting MPD Apr 14 18:30:27 volumio volumio[24303]: info: Output device has changed, restarting Shairport Sync Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio sudo[24472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:27 volumio sudo[24472]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:27 volumio sudo[24472]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:27 volumio sudo[24474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 18:30:27 volumio sudo[24474]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 14 18:30:27 volumio volumio[24303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 18:30:27 volumio systemd[1]: mpd.service: Succeeded. Apr 14 18:30:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 14 18:30:27 volumio volumio[24303]: info: MPD Permissions set Apr 14 18:30:27 volumio volumio[24303]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:27 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:27 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 18:30:27 volumio volumio[24303]: info: Starting Shairport Sync Apr 14 18:30:27 volumio systemd[1]: Starting Music Player Daemon... Apr 14 18:30:27 volumio sudo[24486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 18:30:27 volumio sudo[24486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:30:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 18:30:27 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 18:30:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 18:30:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 18:30:27 volumio sudo[24486]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:27 volumio volumio[24303]: info: Shairport-Sync Started Apr 14 18:30:27 volumio volumio[24303]: Error adding Membership: Error: addMembership EINVAL Apr 14 18:30:28 volumio mpd[24480]: Apr 14 18:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 18:30:28 volumio systemd[1]: Started Music Player Daemon. Apr 14 18:30:28 volumio sudo[24474]: pam_unix(sudo:session): session closed for user root Apr 14 18:30:28 volumio volumio[24303]: error: updateQueue error: null Apr 14 18:30:30 volumio volumio[24303]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 14 18:30:30 volumio volumio[24303]: info: CoreStateMachine::getcurrentVolume Apr 14 18:30:30 volumio volumio[24303]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:31 volumio volumio[24303]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:31 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:31 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:31 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:31 volumio volumio[24303]: info: CoreStateMachine::updateTrackBlock Apr 14 18:30:31 volumio volumio[24303]: info: CorePlayQueue::getTrackBlock Apr 14 18:30:31 volumio volumio[24303]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 18:30:31 volumio volumio[24303]: info: VolumeController:: Volume=100 Mute =false Apr 14 18:30:31 volumio volumio[24303]: info: CoreStateMachine::pushState Apr 14 18:30:31 volumio volumio[24303]: info: CorePlayQueue::getTrack 0 Apr 14 18:30:31 volumio volumio[24303]: info: CoreCommandRouter::volumioPushState Apr 14 18:30:32 volumio volumio[24303]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 14 18:30:33 volumio volumio[24303]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 14 18:30:36 volumio volumio[24303]: Cannot compose Albumart path Apr 14 18:30:38 volumio volumio[24303]: info: Preload queue cleared Apr 14 18:30:38 volumio volumio[24303]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 18:30:38 volumio volumio[24303]: info: CoreStateMachine::ClearQueue Apr 14 18:30:38 volumio volumio[24303]: info: CoreStateMachine::stop Apr 14 18:30:38 volumio volumio[24303]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 18:30:38 volumio volumio[24303]: info: CorePlayQueue::clearPlayQueue Apr 14 18:30:38 volumio volumio[24303]: info: CorePlayQueue::saveQueue Apr 14 18:30:38 volumio volumio[24303]: info: CoreCommandRouter::volumioPushQueue Apr 14 18:30:38 volumio volumio[24303]: info: CoreStateMachine::addQueueItems Apr 14 18:30:38 volumio volumio[24303]: info: CorePlayQueue::addQueueItems Apr 14 18:30:38 volumio volumio[24303]: info: Preload queue cleared Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Bethoven - Лунная соната.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Boney_M-Jingle_Bells.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Boney_M-Jingle_Bells.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/da-da-da.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/da-da-da.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/John Oozila - Funky Boogie.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/John Oozila - Funky Boogie.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/kosinsky_-_istoriya__r_.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/kosinsky_-_istoriya__r_.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/реклама_pepsi_da_da.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/реклама_pepsi_da_da.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Naughty Boy - La La La ft. Sam Smith.mp3 Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Naughty Boy - La La La ft. Sam Smith.mp3 in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Chic - Le Freak (Krazytoons Remix).avi Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Chic - Le Freak (Krazytoons Remix).avi in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/ira.m3u Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/ira.m3u in service mpd Apr 14 18:30:38 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Foreing Apr 14 18:30:38 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Foreing in service mpd Apr 14 18:30:54 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/L-Radio Apr 14 18:30:54 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/L-Radio in service mpd Apr 14 18:30:54 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Monte Carlo Apr 14 18:30:54 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Monte Carlo in service mpd Apr 14 18:30:54 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Russian Apr 14 18:30:54 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Russian in service mpd Apr 14 18:30:55 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Russian_l Apr 14 18:30:55 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Russian_l in service mpd Apr 14 18:30:55 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Tanya Apr 14 18:30:55 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Tanya in service mpd Apr 14 18:30:55 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/VA - 100 Disco Workout Music (2014) Apr 14 18:30:55 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/VA - 100 Disco Workout Music (2014) in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/VKontakte Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/VKontakte in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Авторадио Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Авторадио in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Минусовки Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Минусовки in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Одинокий_пастух Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Одинокий_пастух in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/Рингтоны Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/Рингтоны in service mpd Apr 14 18:30:56 volumio volumio[24303]: info: Adding Item to queue: music-library/NAS/Music-PC/efphdxzxrxqzrдля Apr 14 18:30:56 volumio volumio[24303]: info: Exploding uri music-library/NAS/Music-PC/efphdxzxrxqzrдля in service mpd Apr 14 18:30:56 volumio volumio[24303]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 14 18:30:56 volumio volumio[24303]: info: Preparing to generate the ALSA configuration file Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=%D0%91%D0%B5%D1%82%D1%85%D0%BE%D0%B2%D0%B5%D0%BD//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FBethoven%20-%20%D0%9B%D1%83%D0%BD%D0%BD%D0%B0%D1%8F%20%D1%81%D0%BE%D0%BD%D0%B0%D1%82%D0%B0.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Bethoven - Лунная соната.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Boney%20M//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FBoney_M-Jingle_Bells.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Boney_M-Jingle_Bells.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2Fda-da-da.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/da-da-da.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=John%20Ozila//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FJohn%20Oozila%20-%20Funky%20Boogie.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/John Oozila - Funky Boogie.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2Fkosinsky_-_istoriya__r_.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/kosinsky_-_istoriya__r_.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=%C3%88%C3%A7%20%C3%B0%C3%A5%C3%AA%C3%AB%C3%A0%C3%AC%C3%BB%20Pepsi//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2F%D1%80%D0%B5%D0%BA%D0%BB%D0%B0%D0%BC%D0%B0_pepsi_da_da.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/реклама_pepsi_da_da.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Naughty%20Boy/Best%20Of%202013%20-%20Sommerhits/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FNaughty%20Boy%20-%20La%20La%20La%20ft.%20Sam%20Smith.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Naughty Boy - La La La ft. Sam Smith.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FChic%20-%20Le%20Freak%20(Krazytoons%20Remix).avi&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Chic - Le Freak (Krazytoons Remix).avi Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/Acoustic%20Radio%20Session/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%2F30%20Seconds%20To%20Mars%20-%20Buddha%20For%20Mary%20(live).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars/30 Seconds To Mars - Buddha For Mary (live).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/Acoustic%20Radio%20Session/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%2F30%20Seconds%20To%20Mars%20-%20Capricorn%20(live).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars/30 Seconds To Mars - Capricorn (live).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/Acoustic%20Radio%20Session/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%2F30%20Seconds%20To%20Mars%20-%20Oblivion%20(live).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars/30 Seconds To Mars - Oblivion (live).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/Acoustic%20Radio%20Session/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%2F30%20Seconds%20To%20Mars%20-%20The%20Mission%20(live).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars/30 Seconds To Mars - The Mission (live).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F01.%2030%20Seconds%20To%20Mars%20-%20Capricorn%20(A%20Brand%20New%20Name).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/01. 30 Seconds To Mars - Capricorn (A Brand New Name).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F02.%2030%20Seconds%20To%20Mars%20-%20Edge%20of%20the%20Earth.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/02. 30 Seconds To Mars - Edge of the Earth.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F03.%2030%20Seconds%20To%20Mars%20-%20Fallen.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/03. 30 Seconds To Mars - Fallen.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F04.%2030%20Seconds%20To%20Mars%20-%20Oblivion.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/04. 30 Seconds To Mars - Oblivion.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F05.%2030%20Seconds%20To%20Mars%20-%20Buddha%20for%20Mary.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/05. 30 Seconds To Mars - Buddha for Mary.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F06.%2030%20Seconds%20To%20Mars%20-%20Echelon.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/06. 30 Seconds To Mars - Echelon.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F07.%2030%20Seconds%20To%20Mars%20-%20Welcome%20to%20the%20Universe.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/07. 30 Seconds To Mars - Welcome to the Universe.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F08.%2030%20Seconds%20To%20Mars%20-%20The%20Mission.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/08. 30 Seconds To Mars - The Mission.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F09.%2030%20Seconds%20To%20Mars%20-%20End%20of%20the%20Beginning.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/09. 30 Seconds To Mars - End of the Beginning.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F10.%2030%20Seconds%20To%20Mars%20-%2093%20Million%20Miles.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/10. 30 Seconds To Mars - 93 Million Miles.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20To%20Mars/30%20Seconds%20To%20Mars/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%2030%20Seconds%20To%20Mars%2F11.%2030%20Seconds%20To%20Mars%20-%20Year%20Zero.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - 30 Seconds To Mars/11. 30 Seconds To Mars - Year Zero.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20To%20Mars%20-%20This%20Is%20War.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds To Mars - This Is War.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F01.%2030%20Seconds%20to%20Mars%20-%20Attack.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/01. 30 Seconds to Mars - Attack.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F02.%2030%20Seconds%20to%20Mars%20-%20A%20Beautiful%20Lie.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/02. 30 Seconds to Mars - A Beautiful Lie.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F03.%2030%20Seconds%20to%20Mars%20-%20The%20Kill.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/03. 30 Seconds to Mars - The Kill.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F04.%2030%20Seconds%20to%20Mars%20-%20Was%20it%20a%20Dream.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/04. 30 Seconds to Mars - Was it a Dream.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F05.%2030%20Seconds%20to%20Mars%20-%20The%20Fantasy.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/05. 30 Seconds to Mars - The Fantasy.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F06.%2030%20Seconds%20to%20Mars%20-%20Savior.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/06. 30 Seconds to Mars - Savior.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F07.%2030%20Seconds%20to%20Mars%20-%20From%20Yesterday.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/07. 30 Seconds to Mars - From Yesterday.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F08.%2030%20Seconds%20to%20Mars%20-%20The%20Story.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/08. 30 Seconds to Mars - The Story.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F09.%2030%20Seconds%20to%20Mars%20-%20R-Evolve.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/09. 30 Seconds to Mars - R-Evolve.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F10.%2030%20Seconds%20to%20Mars%20-%20A%20Modern%20Myth%20%20Kill%20(Rebirth).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/10. 30 Seconds to Mars - A Modern Myth Kill (Rebirth).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F11.%2030%20Seconds%20to%20Mars%20-%20Battle%20of%20One%20(Bonus%20track).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/11. 30 Seconds to Mars - Battle of One (Bonus track).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=30%20Seconds%20to%20Mars/A%20Beautiful%20Lie/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%202005%20-%20A%20Beautiful%20Lie%2F12.%2030%20Seconds%20to%20Mars%20-%20Hunter%20(Bonus%20track).mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - 2005 - A Beautiful Lie/12. 30 Seconds to Mars - Hunter (Bonus track).mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=//mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2F30%20Seconds%20to%20Mars%2F30%20Seconds%20to%20Mars%20-%20Kings%20and%20Queens.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/30 Seconds to Mars/30 Seconds to Mars - Kings and Queens.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F01%20Semi-Charmed%20Life.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/01 Semi-Charmed Life.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F02%20Walkin%20On%20The%20Sun.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/02 Walkin On The Sun.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F03%20Save%20Tonight.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/03 Save Tonight.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F04%20Mint%20Car.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/04 Mint Car.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F05%20Mad%20About%20You.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/05 Mad About You.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F06%20Trouble.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/06 Trouble.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F07%20Know%20Nothing.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/07 Know Nothing.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F08%20If%20You%20Leave%20Me%20Now.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/08 If You Leave Me Now.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F09%20Brighter%20Than%20Sunshine.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/09 Brighter Than Sunshine.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F10%20Hands%20Of%20Time.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/10 Hands Of Time.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F11%20Look%20What%20You%20Ve%20Done.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/11 Look What You Ve Done.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F12%20Breathe%202%20Am%20.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/12 Breathe 2 Am .mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=Original%20Soundtrack/A%20Lot%20Like%20Love/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA%20Lot%20Like%20Love%2F13%20Maybe%20It%20S%20Just%20Me.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A Lot Like Love/13 Maybe It S Just Me.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=a-Ha/Lifelines/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA-Ha%2FA-ha%20-%20Lifelines.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A-Ha/A-ha - Lifelines.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A-HA/Ballads%202000/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA-Ha%2FA-ha%20-%20Summer%20Moved%20On.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A-Ha/A-ha - Summer Moved On.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=a-Ha/Minor%20Earth%20-%20Major%20Sky/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA-Ha%2FA-ha%20-%20Velvet.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A-Ha/A-ha - Velvet.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/Yoing/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20Caught%20In%20The%20Middlle.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - Caught In The Middlle.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/The%20a%20List/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20No%20more.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - No more.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/The%20a%20List/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20One%20more%20try.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - One more try.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/The%20a%20List/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20Scared.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - Scared.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/Take%20On%20Me%20%5BCD-Single%5D/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20Take%20On%20Me.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - Take On Me.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=A1/The%20a%20List/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FA1%2FA1%20-%20The%20things%20we%20never%20did.mp3&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/A1/A1 - The things we never did.mp3 Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=ABBA/Ring%20Ring/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FABBA%2F1973%20-%20Ring%20Ring%20(1988%20-%20Swedish%20Polar%20-%20POLCD-242)%2FAbba%20-%20Ring%20Ring.cue&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/ABBA/1973 - Ring Ring (1988 - Swedish Polar - POLCD-242)/Abba - Ring Ring.cue Apr 14 18:30:57 volumio volumio[24303]: info: ALBUMART /albumart?cacheid=968&web=ABBA/Ring%20Ring/mega&path=%2Fmnt%2FNAS%2FMusic-PC%2FForeing%2FABBA%2F1973%20-%20Ring%20Ring%20(1988%20-%20Swedish%20Polar%20-%20POLCD-242)%2FAbba%20-%20Ring%20Ring.cue&metadata=false Apr 14 18:30:57 volumio volumio[24303]: info: URI /mnt/NAS/Music-PC/Foreing/ABBA/1973 - Ring Ring (1988 - Swedish Polar - POLCD-242)/Abba - Ring Ring.cue Apr 14 18:30:57 volumio volumio[24303]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:30:57 volumio volumio[24303]: Error: Unable to resolve or reject the same promise twice Apr 14 18:30:57 volumio volumio[24303]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 18:30:57 volumio volumio[24303]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 14 18:30:57 volumio volumio[24303]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 18:30:57 volumio volumio[24303]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 18:30:57 volumio volumio[24303]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 18:30:57 volumio volumio[24303]: at Socket.emit (events.js:315:20) Apr 14 18:30:57 volumio volumio[24303]: at addChunk (internal/streams/readable.js:309:12) Apr 14 18:30:57 volumio volumio[24303]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 14 18:30:57 volumio volumio[24303]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 14 18:30:57 volumio volumio[24303]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 18:30:57 volumio volumio[24303]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:30:57 volumio sudo[24508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 18:29 Apr 14 18:30:57 volumio sudo[24508]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"