-- Logs begin at Thu 2024-08-22 16:32:45 CST, end at Fri 2024-08-23 09:53:43 CST. -- Aug 23 09:52:01 volumio-lj volumio[24528]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 23 09:52:01 volumio-lj volumio[24528]: info: Retrying Login Due to Network Error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:09 volumio-lj volumio[24528]: error: error Aug 23 09:52:11 volumio-lj volumio[24528]: info: MyVolumio login type: Token Aug 23 09:52:11 volumio-lj volumio[24528]: error: error Aug 23 09:52:11 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:12 volumio-lj volumio[24528]: error: error Aug 23 09:52:41 volumio-lj volumio[24528]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 23 09:52:41 volumio-lj volumio[24528]: info: Retrying Login Due to Network Error Aug 23 09:52:51 volumio-lj volumio[24528]: info: MyVolumio login type: Token Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:52:59 volumio-lj volumio[24528]: error: error Aug 23 09:53:00 volumio-lj volumio[24528]: error: error Aug 23 09:53:00 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:02 volumio-lj volumio[24528]: error: error Aug 23 09:53:03 volumio-lj volumio[24528]: error: error Aug 23 09:53:21 volumio-lj volumio[24528]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 23 09:53:21 volumio-lj volumio[24528]: info: Retrying Login Due to Network Error Aug 23 09:53:30 volumio-lj volumio[24528]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 09:53:30 volumio-lj volumio[24528]: Error: connect ETIMEDOUT 202.160.129.37:443 Aug 23 09:53:30 volumio-lj volumio[24528]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 23 09:53:30 volumio-lj volumio[24528]: errno: -110, Aug 23 09:53:30 volumio-lj volumio[24528]: code: 'ETIMEDOUT', Aug 23 09:53:30 volumio-lj volumio[24528]: syscall: 'connect', Aug 23 09:53:30 volumio-lj volumio[24528]: address: '202.160.129.37', Aug 23 09:53:30 volumio-lj volumio[24528]: port: 443 Aug 23 09:53:30 volumio-lj volumio[24528]: } Aug 23 09:53:30 volumio-lj volumio[24528]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 09:53:31 volumio-lj sudo[24750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 09:52 Aug 23 09:53:31 volumio-lj sudo[24750]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:31 volumio-lj sudo[24750]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:32 volumio-lj volumio-remote-updater[461]: [2024-08-23 09:53:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 23 09:53:32 volumio-lj volumio-remote-updater[461]: [2024-08-23 09:53:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 23 09:53:33 volumio-lj systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 23 09:53:33 volumio-lj systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 23 09:53:33 volumio-lj systemd[1]: Started dynamicswap service. Aug 23 09:53:33 volumio-lj systemd[1]: dynamicswap.service: Succeeded. Aug 23 09:53:33 volumio-lj systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 23 09:53:33 volumio-lj systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21. Aug 23 09:53:33 volumio-lj systemd[1]: Started dynamicswap service. Aug 23 09:53:33 volumio-lj systemd[1]: Stopped Volumio Backend Module. Aug 23 09:53:33 volumio-lj systemd[1]: Started Volumio Backend Module. Aug 23 09:53:33 volumio-lj systemd[1]: dynamicswap.service: Succeeded. Aug 23 09:53:35 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:35 volumio-lj volumio[24764]: info: ----- Volumio3 ---- Aug 23 09:53:35 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:35 volumio-lj volumio[24764]: info: ----- System startup ---- Aug 23 09:53:35 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:35 volumio-lj volumio[24764]: info: MYVOLUMIO Environment detected Aug 23 09:53:36 volumio-lj volumio[24764]: info: Plugin folders cleanup Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning into folder /volumio/app/plugins/ Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category audio_interface Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category miscellanea Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category music_service Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category plugins.json Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category system_controller Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning category user_interface Aug 23 09:53:36 volumio-lj volumio[24764]: info: Scanning into folder /data/plugins/ Aug 23 09:53:36 volumio-lj volumio[24764]: info: Plugin folders cleanup completed Aug 23 09:53:36 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:36 volumio-lj volumio[24764]: info: ----- Core plugins startup ---- Aug 23 09:53:36 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:36 volumio-lj volumio[24764]: info: Loading plugins from folder /volumio/app/plugins/ Aug 23 09:53:36 volumio-lj volumio[24764]: info: Adding plugin upnp to MyMusic Plugins Aug 23 09:53:36 volumio-lj volumio[24764]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 23 09:53:36 volumio-lj volumio[24764]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 23 09:53:36 volumio-lj volumio[24764]: info: Loading plugins from folder /data/plugins/ Aug 23 09:53:36 volumio-lj volumio[24764]: info: Loading plugin "system"... Aug 23 09:53:36 volumio-lj volumio[24764]: info: Loading plugin "appearance"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "network"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Refreshing Cached IP Addresses Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "services"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "alsa_controller"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "wizard"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "volumio_command_line_client"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "upnp"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: [1724378017255] Starting Upmpd Daemon Aug 23 09:53:37 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "my_music"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "mpd"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Creating MPD Configuration file Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "upnp_browser"... Aug 23 09:53:37 volumio-lj volumio[24764]: info: Loading plugin "networkfs"... Aug 23 09:53:38 volumio-lj volumio-remote-updater[461]: [2024-08-23 09:53:38] [connect] Successful connection Aug 23 09:53:38 volumio-lj sudo[24791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 09:53:38 volumio-lj sudo[24789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 09:53:38 volumio-lj sudo[24789]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:38 volumio-lj sudo[24797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 09:53:38 volumio-lj sudo[24789]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:38 volumio-lj sudo[24795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 09:53:38 volumio-lj sudo[24795]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:38 volumio-lj sudo[24797]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:38 volumio-lj sudo[24791]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:38 volumio-lj sudo[24795]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:38 volumio-lj sudo[24787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 09:53:38 volumio-lj sudo[24787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:38 volumio-lj volumio[24764]: info: Cannot mount NAS Roon at system boot, trial number 1 ,retrying in 5 seconds Aug 23 09:53:38 volumio-lj volumio[24764]: info: Starting Udev Watcher for removable devices Aug 23 09:53:38 volumio-lj sudo[24787]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:38 volumio-lj systemd[1]: Stopping Music Player Daemon... Aug 23 09:53:38 volumio-lj systemd[1]: mpd.service: Succeeded. Aug 23 09:53:38 volumio-lj systemd[1]: Stopped Music Player Daemon. Aug 23 09:53:38 volumio-lj systemd[1]: Starting Music Player Daemon... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Ignoring mount for partition: boot Aug 23 09:53:38 volumio-lj volumio[24764]: info: Ignoring mount for partition: volumio Aug 23 09:53:38 volumio-lj volumio[24764]: info: Ignoring mount for partition: volumio_data Aug 23 09:53:38 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "alarm-clock"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "airplay_emulation"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Starting Shairport Sync Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "last_100"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "webradio"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "i2s_dacs"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "volumiodiscovery"... Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** For more information see Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 09:53:38 volumio-lj volumio[24764]: *** WARNING *** For more information see Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** For more information see Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 23 09:53:38 volumio-lj node[24764]: *** WARNING *** For more information see Aug 23 09:53:38 volumio-lj volumio[24764]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 23 09:53:38 volumio-lj volumio[24764]: info: Discovery: Started advertising with name: Volumio-LJ Aug 23 09:53:38 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "outputs"... Aug 23 09:53:38 volumio-lj volumio[24764]: info: Loading plugin "albumart"... Aug 23 09:53:39 volumio-lj volumio[24764]: info: Plugin example_plugin is not enabled Aug 23 09:53:39 volumio-lj volumio[24764]: info: Loading plugin "inputs"... Aug 23 09:53:39 volumio-lj sudo[24791]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:39 volumio-lj volumio[24764]: info: Loading plugin "updater_comm"... Aug 23 09:53:39 volumio-lj volumio[24764]: info: Plugin mpdemulation is not enabled Aug 23 09:53:39 volumio-lj volumio[24764]: info: Loading plugin "rest_api"... Aug 23 09:53:39 volumio-lj volumio[24764]: info: Loading plugin "websocket"... Aug 23 09:53:39 volumio-lj volumio[24764]: info: Loading i18n strings for locale zh Aug 23 09:53:39 volumio-lj volumio[24764]: Updating browse sources language Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 09:53:39 volumio-lj volumio[24764]: Forking 3 albumart workers Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::initPlayerControls Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 09:53:39 volumio-lj volumio[24764]: Express server listening on port 3000 Aug 23 09:53:39 volumio-lj volumio[24764]: [Metrics] WebUI: 4s 945.67ms Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreStateMachine::resetVolumioState Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreStateMachine::getcurrentVolume Aug 23 09:53:39 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 09:53:39 volumio-lj volumio[24764]: info: MPD Permissions set Aug 23 09:53:39 volumio-lj volumio[24764]: verbose: New Socket.io Connection to 10.168.1.6 from 10.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Total Clients: 1 Aug 23 09:53:39 volumio-lj mpd[24807]: Aug 23 09:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 09:53:39 volumio-lj volumio-remote-updater[461]: [2024-08-23 09:53:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724378017 101 Aug 23 09:53:39 volumio-lj volumio[24764]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Aug 23 09:53:40 volumio-lj mpd[24807]: Aug 23 09:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 23 09:53:40 volumio-lj mpd[24807]: Aug 23 09:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 23 09:53:40 volumio-lj volumio[24764]: verbose: New Socket.io Connection to 10.168.1.6 from 10.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Total Clients: 3 Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::pushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrack 0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioPushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::updateTrackBlock Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrackBlock Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: Reloading queue from file Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::setRepeat false single undefined Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::pushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrack 0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioPushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::setRandom null Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::pushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrack 0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioPushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: Setting Device type: Raspberry PI Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreStateMachine::pushState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrack 0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioPushState Aug 23 09:53:40 volumio-lj systemd[1]: Started Music Player Daemon. Aug 23 09:53:40 volumio-lj sudo[24797]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:40 volumio-lj volumio[24764]: info: Completed loading Core Plugins Aug 23 09:53:40 volumio-lj volumio[24764]: info: Preparing to generate the ALSA configuration file Aug 23 09:53:40 volumio-lj volumio[24764]: info: Discovery: adding adde93ac-db31-49b1-9b53-8f8efe7a99d9 Aug 23 09:53:40 volumio-lj volumio[24764]: info: Discovery: Found device Volumio-LJ Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioGetState Aug 23 09:53:40 volumio-lj volumio[24764]: info: CorePlayQueue::getTrack 0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 23 09:53:40 volumio-lj volumio[24764]: info: Reading ALSA contributions from plugins. Aug 23 09:53:40 volumio-lj volumio[24764]: Starting albumart workers Aug 23 09:53:40 volumio-lj volumio[24764]: error: updateQueue error: null Aug 23 09:53:40 volumio-lj volumio[24764]: info: MPD running with PID24807 Aug 23 09:53:40 volumio-lj volumio[24764]: ,establishing connection Aug 23 09:53:40 volumio-lj volumio[24764]: Starting albumart workers Aug 23 09:53:40 volumio-lj volumio[24764]: error: updateQueue error: null Aug 23 09:53:40 volumio-lj volumio[24764]: info: Asound.conf file unchanged, so no further update is needed Aug 23 09:53:40 volumio-lj volumio[24764]: info: Output device has changed, restarting MPD Aug 23 09:53:40 volumio-lj volumio[24764]: info: Output device has changed, restarting Shairport Sync Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: Starting albumart workers Aug 23 09:53:40 volumio-lj sudo[24868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 09:53:40 volumio-lj sudo[24869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 09:53:40 volumio-lj sudo[24869]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:40 volumio-lj sudo[24868]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:40 volumio-lj sudo[24868]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:40 volumio-lj systemd[1]: Stopping Music Player Daemon... Aug 23 09:53:40 volumio-lj volumio[24764]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 09:53:40 volumio-lj volumio[24764]: info: ___________ START PLUGINS ___________ Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 09:53:40 volumio-lj volumio[24764]: info: [1724378020950] CoreMusicLibrary::Adding element Media Servers Aug 23 09:53:40 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 09:53:40 volumio-lj systemd[1]: mpd.service: Succeeded. Aug 23 09:53:40 volumio-lj systemd[1]: Stopped Music Player Daemon. Aug 23 09:53:40 volumio-lj systemd[1]: Starting Music Player Daemon... Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:41 volumio-lj volumio[24764]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:41 volumio-lj volumio[24764]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 09:53:41 volumio-lj volumio[24764]: info: [1724378021294] CoreMusicLibrary::Adding element Last_100 Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 09:53:41 volumio-lj volumio[24764]: info: [1724378021303] CoreMusicLibrary::Adding element Webradio Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 09:53:41 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 09:53:41 volumio-lj volumio[24764]: info: Volumio Calling Home Aug 23 09:53:41 volumio-lj volumio[24764]: info: Completed starting Core Plugins Aug 23 09:53:41 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:41 volumio-lj volumio[24764]: info: ----- MyVolumio plugins startup ---- Aug 23 09:53:41 volumio-lj volumio[24764]: info: ------------------------------------------- Aug 23 09:53:41 volumio-lj volumio[24764]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 23 09:53:41 volumio-lj volumio[24764]: info: MPD Permissions set Aug 23 09:53:41 volumio-lj volumio[24764]: error: error Aug 23 09:53:41 volumio-lj volumio[24764]: error: error Aug 23 09:53:41 volumio-lj volumio[24764]: error: error Aug 23 09:53:41 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: info: Volumio called home Aug 23 09:53:42 volumio-lj mpd[24883]: Aug 23 09:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj mpd[24883]: Aug 23 09:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 23 09:53:42 volumio-lj mpd[24883]: Aug 23 09:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: error: error Aug 23 09:53:42 volumio-lj volumio[24764]: info: Starting Shairport Sync Aug 23 09:53:42 volumio-lj volumio[24764]: info: Starting Shairport Sync Aug 23 09:53:42 volumio-lj volumio[24764]: info: Starting Shairport Sync Aug 23 09:53:43 volumio-lj systemd[1]: Started Music Player Daemon. Aug 23 09:53:43 volumio-lj sudo[24918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 09:53:43 volumio-lj sudo[24869]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:43 volumio-lj sudo[24918]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:43 volumio-lj sudo[24920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 09:53:43 volumio-lj sudo[24916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 09:53:43 volumio-lj sudo[24920]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:43 volumio-lj sudo[24916]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 09:53:43 volumio-lj volumio[24764]: info: Cannot mount NAS Roon at system boot, trial number 2 ,retrying in 5 seconds Aug 23 09:53:43 volumio-lj volumio[24764]: error: updateQueue error: null Aug 23 09:53:43 volumio-lj systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 09:53:43 volumio-lj systemd[1]: shairport-sync.service: Succeeded. Aug 23 09:53:43 volumio-lj systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 09:53:43 volumio-lj volumio[24764]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 09:53:43 volumio-lj systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 09:53:43 volumio-lj volumio[24764]: Error: connect ECONNREFUSED 4.78.139.54:443 Aug 23 09:53:43 volumio-lj volumio[24764]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 23 09:53:43 volumio-lj volumio[24764]: errno: -111, Aug 23 09:53:43 volumio-lj volumio[24764]: code: 'ECONNREFUSED', Aug 23 09:53:43 volumio-lj volumio[24764]: syscall: 'connect', Aug 23 09:53:43 volumio-lj volumio[24764]: address: '4.78.139.54', Aug 23 09:53:43 volumio-lj volumio[24764]: port: 443 Aug 23 09:53:43 volumio-lj volumio[24764]: } Aug 23 09:53:43 volumio-lj volumio[24764]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 09:53:43 volumio-lj sudo[24918]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:43 volumio-lj sudo[24920]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:43 volumio-lj sudo[24916]: pam_unix(sudo:session): session closed for user root Aug 23 09:53:43 volumio-lj sudo[24939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 09:52 Aug 23 09:53:43 volumio-lj sudo[24939]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"