-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-10-17 15:44:41 CEST. -- Oct 17 15:43:01 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:12 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:13 volumio volumio[1417]: info: Listing playlists Oct 17 15:43:13 volumio volumio[1417]: info: Listing playlists Oct 17 15:43:19 volumio volumio[1417]: info: sendMpdCommand status took 27641 milliseconds Oct 17 15:43:19 volumio volumio[1417]: info: sendMpdCommand status took 27642 milliseconds Oct 17 15:43:19 volumio volumio[1417]: info: sendMpdCommand status took 27642 milliseconds Oct 17 15:43:19 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:19 volumio volumio[1417]: info: Command Router : Notfying DB Updatefalse Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::Close All Modals sent Oct 17 15:43:19 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:19 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:19 volumio volumio[1417]: info: ControllerMpd::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::servicePushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:19 volumio volumio[1417]: info: CorePlayQueue::getTrack 0 Oct 17 15:43:19 volumio volumio[1417]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 15:43:19 volumio volumio[1417]: verbose: CURRENT POSITION 0 Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::syncState stateService stop Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::syncState currentStatus pause Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:19 volumio volumio[1417]: info: ControllerMpd::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::servicePushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:19 volumio volumio[1417]: info: CorePlayQueue::getTrack 0 Oct 17 15:43:19 volumio volumio[1417]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 15:43:19 volumio volumio[1417]: verbose: CURRENT POSITION 0 Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::syncState stateService stop Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::syncState currentStatus pause Oct 17 15:43:19 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:19 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:19 volumio volumio[1417]: info: ------------------------------ 27763ms Oct 17 15:43:19 volumio volumio[1417]: info: ------------------------------ 27763ms Oct 17 15:43:22 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:31 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:32 volumio volumio[1417]: info: Listing playlists Oct 17 15:43:32 volumio volumio[1417]: info: Listing playlists Oct 17 15:43:43 volumio volumio[1417]: info: sendMpdCommand status took 52080 milliseconds Oct 17 15:43:43 volumio volumio[1417]: info: sendMpdCommand status took 52087 milliseconds Oct 17 15:43:43 volumio volumio[1417]: info: sendMpdCommand status took 52086 milliseconds Oct 17 15:43:43 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:43 volumio volumio[1417]: info: Command Router : Notfying DB Updatefalse Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::Close All Modals sent Oct 17 15:43:43 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:43 volumio volumio[1417]: verbose: ControllerMpd::parseState Oct 17 15:43:43 volumio volumio[1417]: info: ControllerMpd::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::servicePushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:43 volumio volumio[1417]: info: CorePlayQueue::getTrack 0 Oct 17 15:43:43 volumio volumio[1417]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 15:43:43 volumio volumio[1417]: verbose: CURRENT POSITION 0 Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::syncState stateService stop Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::syncState currentStatus pause Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:43 volumio volumio[1417]: info: ControllerMpd::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::servicePushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:43 volumio volumio[1417]: info: CorePlayQueue::getTrack 0 Oct 17 15:43:43 volumio volumio[1417]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 15:43:43 volumio volumio[1417]: verbose: CURRENT POSITION 0 Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::syncState stateService stop Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::syncState currentStatus pause Oct 17 15:43:43 volumio volumio[1417]: info: CoreStateMachine::pushState Oct 17 15:43:43 volumio volumio[1417]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:43 volumio volumio[1417]: info: ------------------------------ 52227ms Oct 17 15:43:43 volumio volumio[1417]: info: ------------------------------ 52229ms Oct 17 15:43:43 volumio volumio[1417]: error: error Oct 17 15:43:43 volumio volumio[1417]: error: error Oct 17 15:43:43 volumio volumio[1417]: error: error Oct 17 15:43:43 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 17 15:43:44 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 15:43:44 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 17 15:43:44 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 15:43:44 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 17 15:43:44 volumio volumio[1417]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: Received Get System Info Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:43:44 volumio volumio[1417]: info: Discovery: Getting this device information Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 15:43:44 volumio volumio[1417]: info: Received Get System Info Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:43:44 volumio volumio[1417]: info: Discovery: Getting this device information Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetState Oct 17 15:43:44 volumio volumio[1417]: info: Listing playlists Oct 17 15:43:44 volumio volumio[1417]: info: CoreCommandRouter::volumioGetQueue Oct 17 15:43:44 volumio volumio[1417]: info: CoreStateMachine::getQueue Oct 17 15:43:44 volumio volumio[1417]: info: CorePlayQueue::getQueue Oct 17 15:43:46 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 15:43:49 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 15:43:49 volumio volumio[1417]: info: CURURI: music-library/NAS/SNAS/Misc Oct 17 15:43:49 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 15:43:49 volumio volumio[1417]: info: CURURI: music-library/NAS/SNAS/Misc Oct 17 15:43:49 volumio volumio[1417]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 17 15:43:50 volumio volumio[1417]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 15:43:50 volumio volumio[1417]: info: CURURI: music-library/NAS/SNAS/Misc Oct 17 15:43:50 volumio volumio[1417]: <--- Last few GCs ---> Oct 17 15:43:50 volumio volumio[1417]: [1417:0x4c3d788] 56584815 ms: Scavenge 190.7 (197.1) -> 190.6 (197.1) MB, 0.7 / 0.0 ms (average mu = 0.916, current mu = 0.914) allocation failure Oct 17 15:43:50 volumio volumio[1417]: [1417:0x4c3d788] 56584819 ms: Scavenge (reduce) 190.7 (197.2) -> 190.7 (197.2) MB, 2.3 / 0.0 ms (average mu = 0.916, current mu = 0.914) allocation failure Oct 17 15:43:50 volumio volumio[1417]: [1417:0x4c3d788] 56584910 ms: Scavenge (reduce) 205.4 (209.9) -> 205.4 (209.9) MB, 4.4 / 0.0 ms (average mu = 0.916, current mu = 0.914) allocation failure Oct 17 15:43:50 volumio volumio[1417]: <--- JS stacktrace ---> Oct 17 15:43:50 volumio volumio[1417]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Oct 17 15:43:50 volumio volumio-remote-updater[654]: [2024-10-17 15:43:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 15:43:50 volumio volumio-remote-updater[654]: [2024-10-17 15:43:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 15:43:50 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Oct 17 15:43:50 volumio go-librespot[1594]: time="2024-10-17T15:43:50+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 17 15:43:50 volumio systemd[1]: volumio.service: Failed with result 'signal'. Oct 17 15:43:51 volumio systemd[1]: Started dynamicswap service. Oct 17 15:43:51 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 17 15:43:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 17 15:43:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 17 15:43:51 volumio systemd[1]: Started dynamicswap service. Oct 17 15:43:51 volumio systemd[1]: Stopped Volumio Backend Module. Oct 17 15:43:51 volumio systemd[1]: Started Volumio Backend Module. Oct 17 15:43:51 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 17 15:43:53 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:43:53 volumio volumio[9265]: info: ----- Volumio3 ---- Oct 17 15:43:53 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:43:53 volumio volumio[9265]: info: ----- System startup ---- Oct 17 15:43:53 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:43:54 volumio volumio[9265]: info: MYVOLUMIO Environment detected Oct 17 15:43:54 volumio volumio[9265]: info: Plugin folders cleanup Oct 17 15:43:54 volumio volumio[9265]: info: Scanning into folder /volumio/app/plugins/ Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category audio_interface Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category miscellanea Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category music_service Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category plugins.json Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category system_controller Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category user_interface Oct 17 15:43:54 volumio volumio[9265]: info: Scanning into folder /data/plugins/ Oct 17 15:43:54 volumio volumio[9265]: info: Scanning category music_service Oct 17 15:43:54 volumio volumio[9265]: info: Plugin folders cleanup completed Oct 17 15:43:54 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:43:54 volumio volumio[9265]: info: ----- Core plugins startup ---- Oct 17 15:43:54 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:43:54 volumio volumio[9265]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 15:43:54 volumio volumio[9265]: info: Adding plugin upnp to MyMusic Plugins Oct 17 15:43:54 volumio volumio[9265]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 15:43:54 volumio volumio[9265]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 15:43:54 volumio volumio[9265]: info: Loading plugins from folder /data/plugins/ Oct 17 15:43:54 volumio volumio[9265]: info: Loading plugin "system"... Oct 17 15:43:54 volumio volumio[9265]: info: Loading plugin "appearance"... Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "network"... Oct 17 15:43:55 volumio volumio[9265]: info: Refreshing Cached IP Addresses Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "services"... Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "alsa_controller"... Oct 17 15:43:55 volumio sudo[9294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 15:43:55 volumio sudo[9296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 15:43:55 volumio sudo[9292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 15:43:55 volumio sudo[9294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:43:55 volumio sudo[9296]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:43:55 volumio sudo[9292]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:43:55 volumio sudo[9294]: pam_unix(sudo:session): session closed for user root Oct 17 15:43:55 volumio sudo[9292]: pam_unix(sudo:session): session closed for user root Oct 17 15:43:55 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "wizard"... Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "networkfs"... Oct 17 15:43:55 volumio volumio[9265]: info: Cannot mount NAS SNAS at system boot, trial number 1 ,retrying in 5 seconds Oct 17 15:43:55 volumio volumio[9265]: info: Starting Udev Watcher for removable devices Oct 17 15:43:55 volumio volumio[9265]: info: Ignoring mount for partition: boot Oct 17 15:43:55 volumio volumio[9265]: info: Ignoring mount for partition: volumio Oct 17 15:43:55 volumio volumio[9265]: info: Ignoring mount for partition: volumio_data Oct 17 15:43:55 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "volumio_command_line_client"... Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "upnp"... Oct 17 15:43:55 volumio volumio[9265]: info: [1729172635934] Starting Upmpd Daemon Oct 17 15:43:55 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "my_music"... Oct 17 15:43:55 volumio volumio[9265]: info: Loading plugin "mpd"... Oct 17 15:43:55 volumio volumio-remote-updater[654]: [2024-10-17 15:43:55] [connect] Successful connection Oct 17 15:43:56 volumio volumio[9265]: info: Loading plugin "upnp_browser"... Oct 17 15:43:56 volumio sudo[9296]: pam_unix(sudo:session): session closed for user root Oct 17 15:43:56 volumio volumio[9265]: info: Loading plugin "alarm-clock"... Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "airplay_emulation"... Oct 17 15:43:57 volumio volumio[9265]: info: Starting Shairport Sync Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "last_100"... Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "webradio"... Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "i2s_dacs"... Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "volumiodiscovery"... Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** For more information see Oct 17 15:43:57 volumio node[9265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:43:57 volumio volumio[9265]: *** WARNING *** For more information see Oct 17 15:43:57 volumio node[9265]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:43:57 volumio node[9265]: *** WARNING *** For more information see Oct 17 15:43:57 volumio node[9265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 15:43:57 volumio node[9265]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:43:57 volumio node[9265]: *** WARNING *** For more information see Oct 17 15:43:57 volumio volumio[9265]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 15:43:57 volumio volumio[9265]: info: Discovery: Started advertising with name: Volumio Oct 17 15:43:57 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:43:57 volumio volumio[9265]: info: Loading plugin "spop"... Oct 17 15:43:58 volumio volumio[9265]: info: Loading plugin "outputs"... Oct 17 15:43:58 volumio volumio[9265]: info: Loading plugin "albumart"... Oct 17 15:43:58 volumio volumio[9265]: info: Plugin example_plugin is not enabled Oct 17 15:43:58 volumio volumio[9265]: info: Loading plugin "inputs"... Oct 17 15:43:58 volumio volumio[9265]: info: Loading plugin "updater_comm"... Oct 17 15:43:59 volumio volumio[9265]: info: Plugin mpdemulation is not enabled Oct 17 15:43:59 volumio volumio[9265]: info: Loading plugin "rest_api"... Oct 17 15:43:59 volumio volumio[9265]: info: Loading plugin "websocket"... Oct 17 15:43:59 volumio volumio[9265]: info: Starting Socket.io Server version 2.3.0 Oct 17 15:43:59 volumio volumio[9265]: info: Loading i18n strings for locale en Oct 17 15:43:59 volumio volumio[9265]: Updating browse sources language Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:43:59 volumio volumio[9265]: Forking 3 albumart workers Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::initPlayerControls Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:43:59 volumio volumio[9265]: Express server listening on port 3000 Oct 17 15:43:59 volumio volumio[9265]: [Metrics] WebUI: 7s 65.13ms Oct 17 15:43:59 volumio volumio[9265]: info: CoreStateMachine::resetVolumioState Oct 17 15:43:59 volumio volumio[9265]: info: CoreStateMachine::getcurrentVolume Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 15:43:59 volumio volumio[9265]: info: CoreStateMachine::pushState Oct 17 15:43:59 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:43:59 volumio volumio[9265]: info: CoreCommandRouter::volumioPushState Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 17 15:43:59 volumio volumio-remote-updater[654]: [2024-10-17 15:43:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729172635 101 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Oct 17 15:43:59 volumio volumio[9265]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Oct 17 15:44:00 volumio volumio[9265]: info: Reloading queue from file Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: CoreStateMachine::setRepeat true single undefined Oct 17 15:44:00 volumio volumio[9265]: info: CoreStateMachine::pushState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioPushState Oct 17 15:44:00 volumio volumio[9265]: info: CoreStateMachine::setRandom true Oct 17 15:44:00 volumio volumio[9265]: info: CoreStateMachine::pushState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioPushState Oct 17 15:44:00 volumio volumio[9265]: info: Setting Device type: Raspberry PI Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: Completed loading Core Plugins Oct 17 15:44:00 volumio volumio[9265]: info: Preparing to generate the ALSA configuration file Oct 17 15:44:00 volumio volumio[9265]: info: Received Get System Info Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:44:00 volumio volumio[9265]: info: Discovery: Getting this device information Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 15:44:00 volumio volumio[9265]: info: Received Get System Info Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:44:00 volumio volumio[9265]: info: Discovery: Getting this device information Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:00 volumio volumio[9265]: info: Listing playlists Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioGetQueue Oct 17 15:44:00 volumio volumio[9265]: info: CoreStateMachine::getQueue Oct 17 15:44:00 volumio volumio[9265]: info: CorePlayQueue::getQueue Oct 17 15:44:00 volumio volumio[9265]: info: Asound.conf file unchanged, so no further update is needed Oct 17 15:44:00 volumio volumio[9265]: info: Output device has changed, restarting MPD Oct 17 15:44:00 volumio volumio[9265]: info: Output device has changed, restarting Shairport Sync Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: Starting albumart workers Oct 17 15:44:00 volumio sudo[9344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 15:44:00 volumio sudo[9344]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:00 volumio sudo[9344]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:00 volumio sudo[9346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 15:44:00 volumio volumio[9265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:00 volumio sudo[9346]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:00 volumio volumio[9265]: info: ___________ START PLUGINS ___________ Oct 17 15:44:00 volumio volumio[9265]: info: ControllerMpd::onStart: Initializing MPD Oct 17 15:44:00 volumio volumio[9265]: info: Creating MPD Configuration file Oct 17 15:44:00 volumio systemd[1]: Stopping Music Player Daemon... Oct 17 15:44:00 volumio volumio[9265]: Starting albumart workers Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:00 volumio volumio[9265]: info: [1729172640652] CoreMusicLibrary::Adding element Media Servers Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:00 volumio sudo[9354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 15:44:00 volumio sudo[9354]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:00 volumio sudo[9354]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:00 volumio volumio[9265]: Starting albumart workers Oct 17 15:44:00 volumio sudo[9356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 15:44:00 volumio sudo[9356]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:00 volumio volumio[9265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:00 volumio volumio[9265]: info: [1729172640946] CoreMusicLibrary::Adding element Last_100 Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:00 volumio volumio[9265]: info: [1729172640951] CoreMusicLibrary::Adding element Webradio Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:00 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:44:00 volumio volumio[9265]: info: Initializing BBC Radios Oct 17 15:44:01 volumio systemd[1]: mpd.service: Succeeded. Oct 17 15:44:01 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:01 volumio systemd[1]: Starting Music Player Daemon... Oct 17 15:44:01 volumio volumio[9265]: info: Creating Spotify config file Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: Volumio Calling Home Oct 17 15:44:01 volumio sudo[9371]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 15:44:01 volumio sudo[9371]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:01 volumio sudo[9371]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:01 volumio volumio[9265]: info: Cannot mount NAS SNAS at system boot, trial number 2 ,retrying in 5 seconds Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:01 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:01 volumio volumio[9265]: info: MPD Permissions set Oct 17 15:44:01 volumio volumio[9265]: info: MPD Permissions set Oct 17 15:44:01 volumio volumio[9265]: info: Discovery: adding f8e44e80-b0eb-42ac-813f-87cf58fb6755 Oct 17 15:44:01 volumio volumio[9265]: info: Discovery: Found device Volumio Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:01 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:01 volumio volumio[9265]: info: Discovery: this is already registered, f8e44e80-b0eb-42ac-813f-87cf58fb6755 Oct 17 15:44:01 volumio volumio[9265]: info: Discovery: Found device Volumio Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:01 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:01 volumio volumio[9265]: info: Spotify config file written Oct 17 15:44:01 volumio volumio[9265]: info: Listing playlists Oct 17 15:44:01 volumio volumio[9265]: info: Listing playlists Oct 17 15:44:01 volumio sudo[9399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 17 15:44:01 volumio sudo[9399]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:01 volumio volumio[9265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Oct 17 15:44:01 volumio volumio[9265]: info: Volumio called home Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio systemd[1]: Stopping go-librespot Daemon... Oct 17 15:44:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 17 15:44:01 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 17 15:44:01 volumio systemd[1]: Stopped go-librespot Daemon. Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:01 volumio systemd[1]: Started go-librespot Daemon. Oct 17 15:44:01 volumio go-librespot[9410]: Librespot-go daemon starting... Oct 17 15:44:01 volumio sudo[9399]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:02 volumio go-librespot[9410]: time="2024-10-17T15:44:02+02:00" level=info msg="generated new device id: d1ca962eecd0c7ae485fceaa6037ffae8166d815" Oct 17 15:44:02 volumio go-librespot[9410]: time="2024-10-17T15:44:02+02:00" level=debug msg="stored credentials found for gambit73" Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:02 volumio volumio[9265]: info: Starting Shairport Sync Oct 17 15:44:02 volumio volumio[9265]: info: Starting Shairport Sync Oct 17 15:44:02 volumio sudo[9425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:02 volumio sudo[9425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:02 volumio volumio[9265]: info: Starting Shairport Sync Oct 17 15:44:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 15:44:02 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 15:44:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:02 volumio sudo[9430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:02 volumio sudo[9430]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:02 volumio sudo[9429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:02 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:02 volumio sudo[9429]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:02 volumio sudo[9425]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 15:44:02 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 17 15:44:02 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 15:44:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:02 volumio volumio[9265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 17 15:44:02 volumio volumio[9265]: SPOTIFY: BQBktP8mQWY3lEwDHTdskn2eHUl9Je8avxKRk4xOD_HXWLvul5WILIb7nB_udO4MFCJhcrb9g57_ThE7Ci8E3p1ZgHq4fltHbRZ7G7TAjeivNeAIw9X2X_9HCTfmCVgktWDjSUL6WHMcQIB9lcccbkO3zyxnpATq0LZvw50MJOJIk3Nw9Dgwo8GbPW_eRJjWvNZKOg7Hve_0OzHVH8kaYm14OILOEEHCWlpCDJFkYDtkL-LakHaVZDfd Oct 17 15:44:02 volumio volumio[9265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 17 15:44:02 volumio volumio[9265]: info: New Spotify access token = BQBktP8mQWY3lEwDHTdskn2eHUl9Je8avxKRk4xOD_HXWLvul5WILIb7nB_udO4MFCJhcrb9g57_ThE7Ci8E3p1ZgHq4fltHbRZ7G7TAjeivNeAIw9X2X_9HCTfmCVgktWDjSUL6WHMcQIB9lcccbkO3zyxnpATq0LZvw50MJOJIk3Nw9Dgwo8GbPW_eRJjWvNZKOg7Hve_0OzHVH8kaYm14OILOEEHCWlpCDJFkYDtkL-LakHaVZDfd Oct 17 15:44:02 volumio volumio[9265]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 17 15:44:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:02 volumio go-librespot[9410]: time="2024-10-17T15:44:02+02:00" level=debug msg="obtained new client token: AAAC9n7kDZLOU3gNCinRIKPyPUi7sxImdlZ+fWNbKLTomuWRM8JKsEbaCQXNliEDEF3sYsPJc8QsGuGLXBdTuZWT+0/waF284BGIX8zzjskiRp40J7v2WgEuh6VmmdfD2wdhnxLKED3F8LlSmfeMtXsqRcoL0iWBDCCltNYXG4Km3KaBNSvUDNaYSTiILnRsxKBAgRaZRe65oDjWcGxKRlBoGbzMyM1uZcv9gVMwiDGhCC2s6aypRsKjeg89l2Y=" Oct 17 15:44:02 volumio sudo[9430]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:02 volumio sudo[9429]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:02 volumio volumio[9265]: info: Shairport-Sync Started Oct 17 15:44:02 volumio volumio[9265]: Error adding Membership: Error: addMembership EINVAL Oct 17 15:44:02 volumio volumio[9265]: info: Shairport-Sync Started Oct 17 15:44:02 volumio volumio[9265]: info: Shairport-Sync Started Oct 17 15:44:02 volumio go-librespot[9410]: time="2024-10-17T15:44:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:02 volumio go-librespot[9410]: time="2024-10-17T15:44:02+02:00" level=debug msg="completed keyexchange" Oct 17 15:44:02 volumio volumio[9265]: SPOTIFY: User informations: {"country":"NL","display_name":"gambit73","email":"gambit73@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/gambit73"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/gambit73","id":"gambit73","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8587265176da8c829eb589de05","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8287265176da8c829eb589de05","width":64}],"product":"premium","type":"user","uri":"spotify:user:gambit73"} Oct 17 15:44:02 volumio volumio[9265]: info: Spotify Successfully logged in Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:02 volumio volumio[9265]: info: [1729172642797] CoreMusicLibrary::Adding element Spotify Oct 17 15:44:02 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:02 volumio volumio[9265]: Cannot find translation for source Spotify Oct 17 15:44:03 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="completed challenge" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="authenticated as gambit73" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="authenticated as gambit73" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 17 15:44:03 volumio mpd[9379]: Oct 17 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 17 15:44:03 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="dealer connection opened" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 17 15:44:03 volumio go-librespot[9410]: time="2024-10-17T15:44:03+02:00" level=debug msg="received connection id: OWJjM2E4NGQtNjhiYy00YTM1LWI1MzktYjk4ZjMyMmVmNWUzK2RlYWxlcit0Y3A6Ly8wYWNhNTg4Yy5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArODBDOEE1NDk5NTU0RUVEOUQxOEZCN0NDRUQzQUExRjk1REQ2NjcwOTcwMjlEMkU2NjBERjRENEI5MUYyRDgwMQ==" Oct 17 15:44:04 volumio go-librespot[9410]: time="2024-10-17T15:44:04+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 17 15:44:04 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:04 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 15:44:04 volumio volumio[9265]: info: CURURI: music-library/NAS/SNAS/Misc Oct 17 15:44:04 volumio volumio[9265]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Oct 17 15:44:04 volumio systemd[1]: Started Music Player Daemon. Oct 17 15:44:04 volumio sudo[9346]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:04 volumio sudo[9356]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:04 volumio volumio[9265]: info: Completed starting Core Plugins Oct 17 15:44:04 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:44:04 volumio volumio[9265]: info: ----- MyVolumio plugins startup ---- Oct 17 15:44:04 volumio volumio[9265]: info: ------------------------------------------- Oct 17 15:44:04 volumio volumio[9265]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 15:44:04 volumio volumio[9265]: error: MPD error: The expression evaluated to a falsy value: Oct 17 15:44:04 volumio volumio[9265]: assert.ok(self.idling) Oct 17 15:44:04 volumio volumio[9265]: error: The expression evaluated to a falsy value: Oct 17 15:44:04 volumio volumio[9265]: assert.ok(self.idling) Oct 17 15:44:04 volumio volumio[9265]: error: updateQueue error: null Oct 17 15:44:04 volumio volumio[9265]: info: MPD running with PID9379 Oct 17 15:44:04 volumio volumio[9265]: ,establishing connection Oct 17 15:44:04 volumio volumio[9265]: error: updateQueue error: null Oct 17 15:44:04 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:05 volumio volumio[9265]: info: go-librespot daemon successfully initialized Oct 17 15:44:05 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:05 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:05 volumio sudo[9445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 15:44:05 volumio sudo[9445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:05 volumio sudo[9445]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:05 volumio sudo[9447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 15:44:05 volumio sudo[9447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:06 volumio sudo[9447]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:06 volumio sudo[9451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 15:44:06 volumio sudo[9451]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:06 volumio sudo[9451]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:06 volumio volumio[9265]: info: Upmpdcli Daemon Started Oct 17 15:44:06 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:06 volumio volumio[9265]: info: Cannot mount NAS SNAS at system boot, trial number 3 ,retrying in 5 seconds Oct 17 15:44:06 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:07 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:07 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:08 volumio volumio[9265]: info: Initializing connection to go-librespot Websocket Oct 17 15:44:08 volumio go-librespot[9410]: time="2024-10-17T15:44:08+02:00" level=debug msg="new websocket client" Oct 17 15:44:08 volumio volumio[9265]: info: Connection to go-librespot Websocket established Oct 17 15:44:08 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:08 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:09 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 15:44:09 volumio volumio[9265]: info: CURURI: music-library/NAS/SNAS/Misc Oct 17 15:44:09 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Preloading song: music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=BBE//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FBBE_EnterLoadSave.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FBeeGees_GreaseTheme.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=Bert%20%26%20Ernie//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FBert%26Ernie_InDaTub.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FBloodhoundGang_TheVaginaSong.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FDikkieDik_JatAutoradio.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=French%20Kiss//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FFrenchKiss_DreamALittleDream.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=Stenders%20Vroeg//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FFunny_LeeTowersStad.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: ALBUMART /albumart?cacheid=605&web=//extralarge&path=%2Fmnt%2FNAS%2FSNAS%2FMisc%2FGodfriedBomans_SinterklaasOpReis.mp3&metadata=false Oct 17 15:44:09 volumio volumio[9265]: info: URI /mnt/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 in service mpd Oct 17 15:44:09 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:10 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 in service mpd Oct 17 15:44:10 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Getting Spotify volume Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 in service mpd Oct 17 15:44:11 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Spotify volume: 100 Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Cannot mount NAS SNAS at system boot, trial number 4 ,retrying in 5 seconds Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 in service mpd Oct 17 15:44:12 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 in service mpd Oct 17 15:44:13 volumio volumio[9265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Oct 17 15:44:14 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:14 volumio volumio[9265]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 15:44:14 volumio volumio[9265]: info: CoreStateMachine::ClearQueue Oct 17 15:44:14 volumio volumio[9265]: info: CoreStateMachine::stop Oct 17 15:44:14 volumio volumio[9265]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 15:44:14 volumio volumio[9265]: info: CorePlayQueue::clearPlayQueue Oct 17 15:44:14 volumio volumio[9265]: info: CorePlayQueue::saveQueue Oct 17 15:44:14 volumio volumio[9265]: info: CoreCommandRouter::volumioPushQueue Oct 17 15:44:14 volumio volumio[9265]: info: CoreStateMachine::addQueueItems Oct 17 15:44:14 volumio volumio[9265]: info: CorePlayQueue::addQueueItems Oct 17 15:44:14 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 Oct 17 15:44:14 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 in service mpd Oct 17 15:44:14 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:14 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin bluetooth to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin multiroom to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin metavolumio to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin cd_controller to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 17 15:44:15 volumio volumio[9265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 17 15:44:16 volumio volumio[9265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 17 15:44:16 volumio volumio[9265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 17 15:44:16 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:16 volumio volumio[9265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:16 volumio volumio[9265]: info: Starting MyVolumio Remote Streaming Endpoints Oct 17 15:44:16 volumio volumio[9265]: info: MyVolumio login type: Token Oct 17 15:44:16 volumio volumio[9265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 17 15:44:16 volumio volumio[9265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 17 15:44:17 volumio volumio[9265]: info: Starting Streaming Service Transparent Proxy Oct 17 15:44:17 volumio volumio[9265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 17 15:44:17 volumio volumio[9265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 17 15:44:17 volumio volumio[9265]: info: Streaming services startup Oct 17 15:44:17 volumio volumio[9265]: info: Starting Streaming Daemon Oct 17 15:44:17 volumio sudo[9461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 15:44:17 volumio sudo[9461]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:17 volumio sudo[9461]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:17 volumio volumio[9265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 17 15:44:17 volumio volumio[9265]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 17 15:44:18 volumio volumio[9265]: error: Cannot start Volumio Streaming Daemon Oct 17 15:44:18 volumio volumio[9265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 15:44:18 volumio volumio[9265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 15:44:19 volumio volumio[9265]: STREAMING PROXY: Starting server on port 3245 Oct 17 15:44:19 volumio volumio[9265]: Node JS runtime: 14 Oct 17 15:44:19 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:19 volumio volumio[9265]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 15:44:19 volumio volumio[9265]: info: CoreStateMachine::ClearQueue Oct 17 15:44:19 volumio volumio[9265]: info: CoreStateMachine::stop Oct 17 15:44:19 volumio volumio[9265]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 15:44:19 volumio volumio[9265]: info: CorePlayQueue::clearPlayQueue Oct 17 15:44:19 volumio volumio[9265]: info: CorePlayQueue::saveQueue Oct 17 15:44:19 volumio volumio[9265]: info: CoreCommandRouter::volumioPushQueue Oct 17 15:44:19 volumio volumio[9265]: info: CoreStateMachine::addQueueItems Oct 17 15:44:19 volumio volumio[9265]: info: CorePlayQueue::addQueueItems Oct 17 15:44:19 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 Oct 17 15:44:19 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 in service mpd Oct 17 15:44:19 volumio volumio[9265]: error: MyVolumio Custom Token format not valid, refreshing it Oct 17 15:44:20 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:20 volumio volumio[9265]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 15:44:20 volumio volumio[9265]: info: CoreStateMachine::ClearQueue Oct 17 15:44:20 volumio volumio[9265]: info: CoreStateMachine::stop Oct 17 15:44:20 volumio volumio[9265]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 15:44:20 volumio volumio[9265]: info: CorePlayQueue::clearPlayQueue Oct 17 15:44:20 volumio volumio[9265]: info: CorePlayQueue::saveQueue Oct 17 15:44:20 volumio volumio[9265]: info: CoreCommandRouter::volumioPushQueue Oct 17 15:44:20 volumio volumio[9265]: info: CoreStateMachine::addQueueItems Oct 17 15:44:20 volumio volumio[9265]: info: CorePlayQueue::addQueueItems Oct 17 15:44:20 volumio volumio[9265]: info: Preload queue cleared Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BeeGees_GreaseTheme.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Bert&Ernie_InDaTub.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/BloodhoundGang_TheVaginaSong.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/DikkieDik_JatAutoradio.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/FrenchKiss_DreamALittleDream.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/Funny_LeeTowersStad.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Using cached record of: music-library/NAS/SNAS/Misc/GodfriedBomans_SinterklaasOpReis.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/GraceJones_Storm.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/HansTeeuwen_HetLevenIsKut.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/JJMcKay_UsesOfTheWordFuck.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Jules Deelder__Over Amsterdam.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray__True Colors.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray_00_Love Won't Wait.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX1.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX2.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/MilesDavis_JP_TrackX3.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Contradictions.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_Sometimes.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_Trainspotting_ChooseLife.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Portishead_GiveMeAReason.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_EscapeFromMantua.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/R&J2_Mantua.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RobbieWilliams_IWillSurvive.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Samiro_CouseCouse.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_AllIWannaDo[Acoustic].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/SherylCrow_StrongEnough(Acoustic).mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[BombMixEdit].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[CrudeMix].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Spooks_ThingsIHaveSeen[Sing].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/StGermainFtBlackCoffee_Jazzanova&Lounge_PatriciaKaas.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Synthetik_Cuboctahedron.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Telepopmusik_Breathe[Peugeot].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Tool_Divorced.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_01.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_02.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_06.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/YoungGudz_08.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Ellington&Webster_04_iGotItBadAndThatAintGood.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Goober_Apollo13.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/FranzFerdinand_BetterOnHoliday(BBCAirInScotlandSession9-8-03).mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/TenaciousD_Spiderman.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Korn_CamelSong.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_JuliusPapp&DaveWarrin_IFeelYou[MarkGrantRemix].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PapaRoach_Lithium.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Dogstar_OurLittleVisionary.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/RM01_RoundMidnight.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/South Park_01_Compilation.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/KevinYost_TimeToMoveOn[RichardLesCrees].mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Platipus_211_Quietman_Tranquil(ArtOfTranceRemix).mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/PFProject_ChooseLife(Trainspotting#2).mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/Macy Gray - Keziah Jones_U11_Oh Yeah.mp3 in service mpd Oct 17 15:44:20 volumio volumio[9265]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 Oct 17 15:44:20 volumio volumio[9265]: info: Exploding uri music-library/NAS/SNAS/Misc/NatalieImbruglia_DivingInTheDeepEnd.mp3 in service mpd Oct 17 15:44:21 volumio volumio[9265]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:21 volumio volumio[9265]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:22 volumio volumio[9265]: info: Listing playlists Oct 17 15:44:22 volumio volumio[9265]: info: Listing playlists Oct 17 15:44:25 volumio volumio[9265]: info: MyVolumio login type: Token Oct 17 15:44:29 volumio volumio[9265]: <--- Last few GCs ---> Oct 17 15:44:29 volumio volumio[9265]: [9265:0x4dea788] 38016 ms: Scavenge 164.4 (174.7) -> 164.3 (174.7) MB, 1.1 / 0.0 ms (average mu = 0.933, current mu = 0.962) allocation failure Oct 17 15:44:29 volumio volumio[9265]: [9265:0x4dea788] 38149 ms: Scavenge (reduce) 186.7 (197.1) -> 186.7 (197.1) MB, 3.0 / 0.0 ms (average mu = 0.933, current mu = 0.962) allocation failure Oct 17 15:44:29 volumio volumio[9265]: <--- JS stacktrace ---> Oct 17 15:44:29 volumio volumio[9265]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Oct 17 15:44:29 volumio volumio-remote-updater[654]: [2024-10-17 15:44:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 15:44:29 volumio volumio-remote-updater[654]: [2024-10-17 15:44:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 15:44:29 volumio go-librespot[9410]: time="2024-10-17T15:44:29+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 17 15:44:29 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Oct 17 15:44:29 volumio systemd[1]: volumio.service: Failed with result 'signal'. Oct 17 15:44:29 volumio systemd[1]: Started dynamicswap service. Oct 17 15:44:29 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 17 15:44:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 17 15:44:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 17 15:44:29 volumio systemd[1]: Stopped Volumio Backend Module. Oct 17 15:44:29 volumio systemd[1]: Started Volumio Backend Module. Oct 17 15:44:29 volumio systemd[1]: Started dynamicswap service. Oct 17 15:44:29 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 17 15:44:31 volumio volumio[9475]: info: ------------------------------------------- Oct 17 15:44:31 volumio volumio[9475]: info: ----- Volumio3 ---- Oct 17 15:44:31 volumio volumio[9475]: info: ------------------------------------------- Oct 17 15:44:31 volumio volumio[9475]: info: ----- System startup ---- Oct 17 15:44:31 volumio volumio[9475]: info: ------------------------------------------- Oct 17 15:44:32 volumio volumio[9475]: info: MYVOLUMIO Environment detected Oct 17 15:44:32 volumio volumio[9475]: info: Plugin folders cleanup Oct 17 15:44:32 volumio volumio[9475]: info: Scanning into folder /volumio/app/plugins/ Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category audio_interface Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category miscellanea Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category music_service Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category plugins.json Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category system_controller Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category user_interface Oct 17 15:44:32 volumio volumio[9475]: info: Scanning into folder /data/plugins/ Oct 17 15:44:32 volumio volumio[9475]: info: Scanning category music_service Oct 17 15:44:32 volumio volumio[9475]: info: Plugin folders cleanup completed Oct 17 15:44:32 volumio volumio[9475]: info: ------------------------------------------- Oct 17 15:44:32 volumio volumio[9475]: info: ----- Core plugins startup ---- Oct 17 15:44:32 volumio volumio[9475]: info: ------------------------------------------- Oct 17 15:44:32 volumio volumio[9475]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 15:44:32 volumio volumio[9475]: info: Adding plugin upnp to MyMusic Plugins Oct 17 15:44:32 volumio volumio[9475]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 15:44:32 volumio volumio[9475]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 15:44:32 volumio volumio[9475]: info: Loading plugins from folder /data/plugins/ Oct 17 15:44:32 volumio volumio[9475]: info: Loading plugin "system"... Oct 17 15:44:32 volumio volumio[9475]: info: Loading plugin "appearance"... Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "network"... Oct 17 15:44:33 volumio volumio[9475]: info: Refreshing Cached IP Addresses Oct 17 15:44:33 volumio sudo[9502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 15:44:33 volumio sudo[9502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:33 volumio sudo[9502]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:33 volumio sudo[9504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "services"... Oct 17 15:44:33 volumio sudo[9504]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "alsa_controller"... Oct 17 15:44:33 volumio sudo[9504]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:33 volumio sudo[9507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 15:44:33 volumio sudo[9507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:33 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "wizard"... Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "networkfs"... Oct 17 15:44:33 volumio volumio[9475]: info: Cannot mount NAS SNAS at system boot, trial number 1 ,retrying in 5 seconds Oct 17 15:44:33 volumio volumio[9475]: info: Starting Udev Watcher for removable devices Oct 17 15:44:33 volumio volumio[9475]: info: Ignoring mount for partition: boot Oct 17 15:44:33 volumio volumio[9475]: info: Ignoring mount for partition: volumio Oct 17 15:44:33 volumio volumio[9475]: info: Ignoring mount for partition: volumio_data Oct 17 15:44:33 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "volumio_command_line_client"... Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "upnp"... Oct 17 15:44:33 volumio volumio[9475]: info: [1729172673762] Starting Upmpd Daemon Oct 17 15:44:33 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "my_music"... Oct 17 15:44:33 volumio volumio[9475]: info: Loading plugin "mpd"... Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "upnp_browser"... Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "alarm-clock"... Oct 17 15:44:34 volumio sudo[9507]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:34 volumio volumio-remote-updater[654]: [2024-10-17 15:44:34] [connect] Successful connection Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "airplay_emulation"... Oct 17 15:44:34 volumio volumio[9475]: info: Starting Shairport Sync Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "last_100"... Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "webradio"... Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "i2s_dacs"... Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "volumiodiscovery"... Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** For more information see Oct 17 15:44:34 volumio node[9475]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:44:34 volumio volumio[9475]: *** WARNING *** For more information see Oct 17 15:44:34 volumio node[9475]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:44:34 volumio node[9475]: *** WARNING *** For more information see Oct 17 15:44:34 volumio node[9475]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 15:44:34 volumio node[9475]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 15:44:34 volumio node[9475]: *** WARNING *** For more information see Oct 17 15:44:34 volumio volumio[9475]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 15:44:34 volumio volumio[9475]: info: Discovery: Started advertising with name: Volumio Oct 17 15:44:34 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 15:44:34 volumio volumio[9475]: info: Loading plugin "spop"... Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "outputs"... Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "albumart"... Oct 17 15:44:36 volumio volumio[9475]: info: Plugin example_plugin is not enabled Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "inputs"... Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "updater_comm"... Oct 17 15:44:36 volumio volumio[9475]: info: Plugin mpdemulation is not enabled Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "rest_api"... Oct 17 15:44:36 volumio volumio[9475]: info: Loading plugin "websocket"... Oct 17 15:44:36 volumio volumio[9475]: info: Starting Socket.io Server version 2.3.0 Oct 17 15:44:36 volumio volumio[9475]: info: Loading i18n strings for locale en Oct 17 15:44:36 volumio volumio[9475]: Updating browse sources language Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::initPlayerControls Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: Forking 3 albumart workers Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: Express server listening on port 3000 Oct 17 15:44:36 volumio volumio[9475]: [Metrics] WebUI: 5s 416.62ms Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::resetVolumioState Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::getcurrentVolume Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::pushState Oct 17 15:44:36 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::volumioPushState Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 17 15:44:36 volumio volumio-remote-updater[654]: [2024-10-17 15:44:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729172674 101 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.24 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 17 15:44:36 volumio volumio[9475]: verbose: New Socket.io Connection to 10.168.1.26 from 10.168.1.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:36 volumio volumio[9475]: info: Reloading queue from file Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::setRepeat true single undefined Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::pushState Oct 17 15:44:36 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:44:36 volumio volumio[9475]: info: CoreCommandRouter::volumioPushState Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::setRandom true Oct 17 15:44:36 volumio volumio[9475]: info: CoreStateMachine::pushState Oct 17 15:44:36 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioPushState Oct 17 15:44:37 volumio volumio[9475]: info: Setting Device type: Raspberry PI Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: Received Get System Info Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:44:37 volumio volumio[9475]: info: Discovery: Getting this device information Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 15:44:37 volumio volumio[9475]: info: Received Get System Info Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 15:44:37 volumio volumio[9475]: info: Discovery: Getting this device information Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: info: Listing playlists Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetQueue Oct 17 15:44:37 volumio volumio[9475]: info: CoreStateMachine::getQueue Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getQueue Oct 17 15:44:37 volumio volumio[9475]: info: Completed loading Core Plugins Oct 17 15:44:37 volumio volumio[9475]: info: Preparing to generate the ALSA configuration file Oct 17 15:44:37 volumio volumio[9475]: info: Discovery: adding f8e44e80-b0eb-42ac-813f-87cf58fb6755 Oct 17 15:44:37 volumio volumio[9475]: info: Discovery: Found device Volumio Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:37 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:37 volumio volumio[9475]: Starting albumart workers Oct 17 15:44:37 volumio volumio[9475]: info: Asound.conf file unchanged, so no further update is needed Oct 17 15:44:37 volumio volumio[9475]: info: Output device has changed, restarting MPD Oct 17 15:44:37 volumio volumio[9475]: Starting albumart workers Oct 17 15:44:37 volumio sudo[9591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 15:44:37 volumio sudo[9591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:37 volumio volumio[9475]: info: Output device has changed, restarting Shairport Sync Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:37 volumio sudo[9591]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:37 volumio sudo[9594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 15:44:37 volumio sudo[9594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:37 volumio systemd[1]: Stopping Music Player Daemon... Oct 17 15:44:37 volumio volumio[9475]: Starting albumart workers Oct 17 15:44:37 volumio volumio[9475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:37 volumio volumio[9475]: info: ___________ START PLUGINS ___________ Oct 17 15:44:37 volumio volumio[9475]: info: ControllerMpd::onStart: Initializing MPD Oct 17 15:44:37 volumio volumio[9475]: info: Creating MPD Configuration file Oct 17 15:44:37 volumio systemd[1]: mpd.service: Succeeded. Oct 17 15:44:37 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 15:44:37 volumio systemd[1]: Starting Music Player Daemon... Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:37 volumio volumio[9475]: info: [1729172677984] CoreMusicLibrary::Adding element Media Servers Oct 17 15:44:37 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:38 volumio sudo[9602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 15:44:38 volumio sudo[9602]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:38 volumio sudo[9602]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:38 volumio sudo[9603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 15:44:38 volumio sudo[9603]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 17 15:44:38 volumio systemd[1]: mpd.service: Succeeded. Oct 17 15:44:38 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 15:44:38 volumio volumio[9475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:38 volumio systemd[1]: Starting Music Player Daemon... Oct 17 15:44:38 volumio volumio[9475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:38 volumio volumio[9475]: info: [1729172678240] CoreMusicLibrary::Adding element Last_100 Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:38 volumio volumio[9475]: info: [1729172678245] CoreMusicLibrary::Adding element Webradio Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:44:38 volumio volumio[9475]: info: Initializing BBC Radios Oct 17 15:44:38 volumio sudo[9609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 15:44:38 volumio sudo[9609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:38 volumio sudo[9609]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: Creating Spotify config file Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: Volumio Calling Home Oct 17 15:44:38 volumio volumio[9475]: info: Cannot mount NAS SNAS at system boot, trial number 2 ,retrying in 5 seconds Oct 17 15:44:38 volumio volumio[9475]: info: Discovery: this is already registered, f8e44e80-b0eb-42ac-813f-87cf58fb6755 Oct 17 15:44:38 volumio volumio[9475]: info: Discovery: Found device Volumio Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:38 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:38 volumio volumio[9475]: info: MPD Permissions set Oct 17 15:44:38 volumio volumio[9475]: info: MPD Permissions set Oct 17 15:44:38 volumio volumio[9475]: info: Volumio called home Oct 17 15:44:38 volumio volumio[9475]: info: Spotify config file written Oct 17 15:44:38 volumio volumio[9475]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:38 volumio sudo[9650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 17 15:44:38 volumio sudo[9650]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:38 volumio systemd[1]: Stopping go-librespot Daemon... Oct 17 15:44:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 17 15:44:38 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 17 15:44:38 volumio systemd[1]: Stopped go-librespot Daemon. Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio systemd[1]: Started go-librespot Daemon. Oct 17 15:44:39 volumio go-librespot[9660]: Librespot-go daemon starting... Oct 17 15:44:39 volumio sudo[9650]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 15:44:39 volumio go-librespot[9660]: time="2024-10-17T15:44:39+02:00" level=info msg="generated new device id: 1d91597d9bd9f748840d949c217067b489077425" Oct 17 15:44:39 volumio go-librespot[9660]: time="2024-10-17T15:44:39+02:00" level=debug msg="stored credentials found for gambit73" Oct 17 15:44:39 volumio volumio[9475]: info: Starting Shairport Sync Oct 17 15:44:39 volumio volumio[9475]: info: Starting Shairport Sync Oct 17 15:44:39 volumio volumio[9475]: info: Starting Shairport Sync Oct 17 15:44:39 volumio sudo[9673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:39 volumio sudo[9673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:39 volumio sudo[9677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:39 volumio sudo[9677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 15:44:39 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 15:44:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:39 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:39 volumio sudo[9678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 15:44:39 volumio sudo[9678]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 15:44:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:39 volumio sudo[9673]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:39 volumio go-librespot[9660]: time="2024-10-17T15:44:39+02:00" level=debug msg="obtained new client token: AACNLBxZRBqWLpNRah96qhkaImSIKGLA66SYG9IvtsrTEgcRHKHGpLEijjv73Qdj9WeaE83DJ7kn3g9NeeXktbSPxYQFAEkSPSCvkl1WeE1Ir0Sk3XUp6gcentudy55/IyLiwdr/IIq0gRh2uMyB2Wl7Dwrl0+5XZnX4gRRCpBoWFf5lDKq6INBuo46BAyWD9OC8jyXp+bmEHUQKWnscecKL1mK4CmlUqlasb8ncEFfZT5mZMgOywpEDxoV/TCU=" Oct 17 15:44:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 15:44:39 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 15:44:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 15:44:39 volumio sudo[9677]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:39 volumio sudo[9678]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:39 volumio volumio[9475]: info: Shairport-Sync Started Oct 17 15:44:39 volumio volumio[9475]: Error adding Membership: Error: addMembership EINVAL Oct 17 15:44:39 volumio volumio[9475]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 17 15:44:39 volumio volumio[9475]: SPOTIFY: BQAu2iXiSDfazEceiEMvyefMTk91mG_-UU4tLj7kmTCrZf6AaRGdRG-ixEPbZ25j-nFeRgvS5dUgjIWvR7NODHZ5hnFEY4lMGItTQB1tDYYwvBIIFBT3vcWxs_1aez6qYm4U8PLWhGNoCj1sAUkFT-cJFJJUbA8PdFAwaghlLx4hJV60W79pFaOi8sTwQovfZVW3u8rtvlHgycECbSg0nhEf3HXEFv3kTqtF1nRMgNC4gA3ZjtgSwtTQ Oct 17 15:44:39 volumio volumio[9475]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 17 15:44:39 volumio volumio[9475]: info: New Spotify access token = BQAu2iXiSDfazEceiEMvyefMTk91mG_-UU4tLj7kmTCrZf6AaRGdRG-ixEPbZ25j-nFeRgvS5dUgjIWvR7NODHZ5hnFEY4lMGItTQB1tDYYwvBIIFBT3vcWxs_1aez6qYm4U8PLWhGNoCj1sAUkFT-cJFJJUbA8PdFAwaghlLx4hJV60W79pFaOi8sTwQovfZVW3u8rtvlHgycECbSg0nhEf3HXEFv3kTqtF1nRMgNC4gA3ZjtgSwtTQ Oct 17 15:44:39 volumio volumio[9475]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 17 15:44:39 volumio volumio[9475]: info: Shairport-Sync Started Oct 17 15:44:39 volumio volumio[9475]: info: Shairport-Sync Started Oct 17 15:44:39 volumio go-librespot[9660]: time="2024-10-17T15:44:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 17 15:44:39 volumio go-librespot[9660]: time="2024-10-17T15:44:39+02:00" level=debug msg="completed keyexchange" Oct 17 15:44:39 volumio volumio[9475]: SPOTIFY: User informations: {"country":"NL","display_name":"gambit73","email":"gambit73@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/gambit73"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/gambit73","id":"gambit73","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8587265176da8c829eb589de05","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8287265176da8c829eb589de05","width":64}],"product":"premium","type":"user","uri":"spotify:user:gambit73"} Oct 17 15:44:39 volumio volumio[9475]: info: Spotify Successfully logged in Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 15:44:39 volumio volumio[9475]: info: [1729172679775] CoreMusicLibrary::Adding element Spotify Oct 17 15:44:39 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 15:44:39 volumio volumio[9475]: Cannot find translation for source Spotify Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="completed challenge" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="authenticated as gambit73" Oct 17 15:44:40 volumio mpd[9621]: Oct 17 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 15:44:40 volumio volumio[9475]: info: CoreCommandRouter::volumioGetState Oct 17 15:44:40 volumio volumio[9475]: info: CorePlayQueue::getTrack 0 Oct 17 15:44:40 volumio volumio[9475]: info: Listing playlists Oct 17 15:44:40 volumio volumio[9475]: info: Listing playlists Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="authenticated as gambit73" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="dealer connection opened" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="received connection id: ZDBlYjEyZTktNWY0ZC00OTNlLWI4NjQtMzA1NDUzNDRlODNlK2RlYWxlcit0Y3A6Ly8wYWNhNThhOC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNkI1MzlBQzlGMzUxQ0U1QjJBRkI2RUYxRkJDM0FDREQyMzc5RjA4Nzc0RUM3RTZCMTJBQzFBRjlGM0NCNzhDNQ==" Oct 17 15:44:40 volumio go-librespot[9660]: time="2024-10-17T15:44:40+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 17 15:44:41 volumio volumio[9475]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 15:44:41 volumio systemd[1]: Started Music Player Daemon. Oct 17 15:44:41 volumio sudo[9594]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:41 volumio sudo[9603]: pam_unix(sudo:session): session closed for user root Oct 17 15:44:41 volumio volumio[9475]: info: Preload queue cleared Oct 17 15:44:41 volumio volumio[9475]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 15:44:41 volumio volumio[9475]: info: CoreStateMachine::ClearQueue Oct 17 15:44:41 volumio volumio[9475]: info: CoreStateMachine::stop Oct 17 15:44:41 volumio volumio[9475]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 15:44:41 volumio volumio[9475]: info: CorePlayQueue::clearPlayQueue Oct 17 15:44:41 volumio volumio[9475]: info: CorePlayQueue::saveQueue Oct 17 15:44:41 volumio volumio[9475]: info: CoreCommandRouter::volumioPushQueue Oct 17 15:44:41 volumio volumio[9475]: info: CoreStateMachine::addQueueItems Oct 17 15:44:41 volumio volumio[9475]: info: CorePlayQueue::addQueueItems Oct 17 15:44:41 volumio volumio[9475]: info: Preload queue cleared Oct 17 15:44:41 volumio volumio[9475]: info: Adding Item to queue: music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 Oct 17 15:44:41 volumio volumio[9475]: info: Exploding uri music-library/NAS/SNAS/Misc/BBE_EnterLoadSave.mp3 in service mpd Oct 17 15:44:41 volumio volumio[9475]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 15:44:41 volumio volumio[9475]: TypeError: Cannot read property 'then' of undefined Oct 17 15:44:41 volumio volumio[9475]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Oct 17 15:44:41 volumio volumio[9475]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Oct 17 15:44:41 volumio volumio[9475]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Oct 17 15:44:41 volumio volumio[9475]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 17 15:44:41 volumio volumio[9475]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 17 15:44:41 volumio volumio[9475]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Oct 17 15:44:41 volumio volumio[9475]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Oct 17 15:44:41 volumio volumio[9475]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Oct 17 15:44:41 volumio volumio[9475]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:483:17) Oct 17 15:44:41 volumio volumio[9475]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Oct 17 15:44:41 volumio volumio[9475]: at Socket.emit (events.js:315:20) Oct 17 15:44:41 volumio volumio[9475]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 17 15:44:41 volumio volumio[9475]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 17 15:44:41 volumio volumio[9475]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 15:44:41 volumio sudo[9700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 15:43 Oct 17 15:44:41 volumio sudo[9700]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"