-- Logs begin at Tue 2024-09-03 17:47:56 CST, end at Tue 2024-09-03 17:57:20 CST. -- Sep 03 17:56:58 volumio volumio[6641]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 17:56:58 volumio volumio[6641]: Error: connect ETIMEDOUT 202.160.128.40:80 Sep 03 17:56:58 volumio volumio[6641]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 03 17:56:58 volumio volumio[6641]: errno: -110, Sep 03 17:56:58 volumio volumio[6641]: code: 'ETIMEDOUT', Sep 03 17:56:58 volumio volumio[6641]: syscall: 'connect', Sep 03 17:56:58 volumio volumio[6641]: address: '202.160.128.40', Sep 03 17:56:58 volumio volumio[6641]: port: 80 Sep 03 17:56:58 volumio volumio[6641]: } Sep 03 17:56:58 volumio volumio[6641]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 17:56:59 volumio sudo[7029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 17:55 Sep 03 17:56:59 volumio sudo[7029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:56:59 volumio sudo[7029]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 17:57:02 volumio ntfs-3g[6691]: Unmounting /dev/sda1 (sd) Sep 03 17:57:02 volumio systemd[1]: media-sd.mount: Succeeded. Sep 03 17:57:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 17:57:02 volumio systemd[1]: Started dynamicswap service. Sep 03 17:57:02 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 17:57:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 17:57:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Sep 03 17:57:02 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 17:57:02 volumio systemd[1]: Started Volumio Backend Module. Sep 03 17:57:02 volumio systemd[1]: Started dynamicswap service. Sep 03 17:57:02 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 17:57:06 volumio volumio[7045]: info: ------------------------------------------- Sep 03 17:57:06 volumio volumio[7045]: info: ----- Volumio3 ---- Sep 03 17:57:06 volumio volumio[7045]: info: ------------------------------------------- Sep 03 17:57:06 volumio volumio[7045]: info: ----- System startup ---- Sep 03 17:57:06 volumio volumio[7045]: info: ------------------------------------------- Sep 03 17:57:08 volumio volumio[7045]: info: MYVOLUMIO Environment detected Sep 03 17:57:08 volumio volumio[7045]: info: Plugin folders cleanup Sep 03 17:57:08 volumio volumio[7045]: info: Scanning into folder /volumio/app/plugins/ Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category audio_interface Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category miscellanea Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category music_service Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category plugins.json Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category system_controller Sep 03 17:57:08 volumio volumio[7045]: info: Scanning category user_interface Sep 03 17:57:08 volumio volumio[7045]: info: Scanning into folder /data/plugins/ Sep 03 17:57:08 volumio volumio[7045]: info: Plugin folders cleanup completed Sep 03 17:57:08 volumio volumio[7045]: info: ------------------------------------------- Sep 03 17:57:08 volumio volumio[7045]: info: ----- Core plugins startup ---- Sep 03 17:57:08 volumio volumio[7045]: info: ------------------------------------------- Sep 03 17:57:08 volumio volumio[7045]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 17:57:08 volumio volumio[7045]: info: Adding plugin upnp to MyMusic Plugins Sep 03 17:57:08 volumio volumio[7045]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 17:57:08 volumio volumio[7045]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 17:57:08 volumio volumio[7045]: info: Loading plugins from folder /data/plugins/ Sep 03 17:57:08 volumio volumio[7045]: info: Loading plugin "system"... Sep 03 17:57:08 volumio volumio[7045]: info: Loading plugin "appearance"... Sep 03 17:57:10 volumio volumio[7045]: info: Loading plugin "network"... Sep 03 17:57:10 volumio volumio[7045]: info: Refreshing Cached IP Addresses Sep 03 17:57:10 volumio sudo[7074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 17:57:10 volumio sudo[7074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:57:10 volumio volumio[7045]: info: Loading plugin "services"... Sep 03 17:57:10 volumio sudo[7074]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:10 volumio volumio[7045]: info: Loading plugin "alsa_controller"... Sep 03 17:57:10 volumio sudo[7076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 17:57:10 volumio sudo[7076]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:57:10 volumio sudo[7078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 03 17:57:10 volumio sudo[7078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:57:10 volumio sudo[7076]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:10 volumio sudo[7078]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:10 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 17:57:10 volumio volumio[7045]: info: Loading plugin "wizard"... Sep 03 17:57:10 volumio volumio[7045]: info: Loading plugin "networkfs"... Sep 03 17:57:11 volumio volumio[7045]: info: Starting Udev Watcher for removable devices Sep 03 17:57:11 volumio volumio[7045]: info: Ignoring mount for partition: boot Sep 03 17:57:11 volumio volumio[7045]: info: Ignoring mount for partition: volumio Sep 03 17:57:11 volumio volumio[7045]: info: Ignoring mount for partition: volumio_data Sep 03 17:57:11 volumio volumio[7045]: info: Mounting Device sd Sep 03 17:57:11 volumio sudo[7095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/sd -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 03 17:57:11 volumio sudo[7095]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:57:11 volumio ntfs-3g[7098]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 03 17:57:11 volumio ntfs-3g[7098]: Mounted /dev/sda1 (Read-Write, label "sd", NTFS 3.1) Sep 03 17:57:11 volumio ntfs-3g[7098]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 03 17:57:11 volumio ntfs-3g[7098]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 03 17:57:11 volumio ntfs-3g[7098]: Global ownership and permissions enforced, configuration type 7 Sep 03 17:57:11 volumio sudo[7095]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:11 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 17:57:11 volumio volumio[7045]: info: Loading plugin "volumio_command_line_client"... Sep 03 17:57:11 volumio volumio[7045]: info: Loading plugin "upnp"... Sep 03 17:57:11 volumio volumio[7045]: info: [1725357431741] Starting Upmpd Daemon Sep 03 17:57:11 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 17:57:11 volumio volumio[7045]: info: Loading plugin "my_music"... Sep 03 17:57:11 volumio volumio[7045]: info: Loading plugin "mpd"... Sep 03 17:57:12 volumio volumio[7045]: info: Loading plugin "upnp_browser"... Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "alarm-clock"... Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "airplay_emulation"... Sep 03 17:57:13 volumio volumio[7045]: info: Starting Shairport Sync Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "last_100"... Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "webradio"... Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "i2s_dacs"... Sep 03 17:57:13 volumio volumio[7045]: info: I2S DAC not set, start Auto-detection Sep 03 17:57:13 volumio volumio[7045]: info: Loading plugin "volumiodiscovery"... Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 17:57:14 volumio node[7045]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 17:57:14 volumio node[7045]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** For more information see Sep 03 17:57:14 volumio node[7045]: *** WARNING *** For more information see Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 17:57:14 volumio node[7045]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 17:57:14 volumio node[7045]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 17:57:14 volumio volumio[7045]: *** WARNING *** For more information see Sep 03 17:57:14 volumio node[7045]: *** WARNING *** For more information see Sep 03 17:57:14 volumio volumio[7045]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 17:57:14 volumio volumio[7045]: info: Discovery: Started advertising with name: Volumio Sep 03 17:57:14 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "outputs"... Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "albumart"... Sep 03 17:57:14 volumio volumio[7045]: info: Plugin example_plugin is not enabled Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "inputs"... Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "updater_comm"... Sep 03 17:57:14 volumio volumio[7045]: info: Plugin mpdemulation is not enabled Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "rest_api"... Sep 03 17:57:14 volumio volumio[7045]: info: Loading plugin "websocket"... Sep 03 17:57:14 volumio volumio[7045]: info: Loading i18n strings for locale zh Sep 03 17:57:14 volumio volumio[7045]: Updating browse sources language Sep 03 17:57:14 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 17:57:15 volumio volumio[7045]: Forking 3 albumart workers Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::initPlayerControls Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: Express server listening on port 3000 Sep 03 17:57:15 volumio volumio[7045]: [Metrics] WebUI: 10s 127.16ms Sep 03 17:57:15 volumio volumio[7045]: info: CoreStateMachine::resetVolumioState Sep 03 17:57:15 volumio volumio[7045]: info: CoreStateMachine::getcurrentVolume Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 17:57:15 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 17:57:15 volumio volumio[7045]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 03 17:57:15 volumio volumio[7045]: wlan0 Interface doesn't support scanning. Sep 03 17:57:15 volumio volumio[7045]: info: Cannot use regular scanning, forcing with ap-force Sep 03 17:57:15 volumio sudo[7134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 03 17:57:15 volumio sudo[7134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 17:57:15 volumio sudo[7134]: pam_unix(sudo:session): session closed for user root Sep 03 17:57:15 volumio volumio[7045]: command failed: No such device (-19) Sep 03 17:57:15 volumio volumio[7045]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 03 17:57:15 volumio volumio[7045]: command failed: No such device (-19) Sep 03 17:57:16 volumio volumio[7045]: info: VolumeController:: Volume=35 Mute =false Sep 03 17:57:16 volumio volumio[7045]: info: CoreStateMachine::pushState Sep 03 17:57:17 volumio volumio[7045]: info: CorePlayQueue::getTrack 0 Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::volumioPushState Sep 03 17:57:17 volumio volumio[7045]: info: CoreStateMachine::updateTrackBlock Sep 03 17:57:17 volumio volumio[7045]: info: CorePlayQueue::getTrackBlock Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 17:57:17 volumio volumio[7045]: info: CoreStateMachine::setRepeat false single undefined Sep 03 17:57:17 volumio volumio[7045]: info: CoreStateMachine::pushState Sep 03 17:57:17 volumio volumio[7045]: info: CorePlayQueue::getTrack 0 Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::volumioPushState Sep 03 17:57:17 volumio volumio[7045]: info: CoreStateMachine::setRandom false Sep 03 17:57:17 volumio volumio[7045]: info: CoreStateMachine::pushState Sep 03 17:57:17 volumio volumio[7045]: info: CorePlayQueue::getTrack 0 Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::volumioPushState Sep 03 17:57:17 volumio volumio[7045]: info: Setting Device type: Raspberry PI Sep 03 17:57:17 volumio volumio[7045]: info: Reloading queue from file Sep 03 17:57:17 volumio volumio[7045]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 03 17:57:17 volumio volumio[7045]: Starting albumart workers Sep 03 17:57:17 volumio volumio[7045]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 03 17:57:17 volumio volumio[7045]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 03 17:57:17 volumio volumio[7045]: info: Discovery: adding 873373ba-3225-4bcf-aa8f-76c4e7de2dc7 Sep 03 17:57:17 volumio volumio[7045]: info: Discovery: Found device Volumio Sep 03 17:57:17 volumio volumio[7045]: info: CoreCommandRouter::volumioGetState Sep 03 17:57:17 volumio volumio[7045]: info: CorePlayQueue::getTrack 0 Sep 03 17:57:18 volumio volumio[7045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 17:57:18 volumio volumio[7045]: Starting albumart workers Sep 03 17:57:18 volumio volumio[7045]: Error: connect ECONNREFUSED 4.78.139.50:443 Sep 03 17:57:18 volumio volumio[7045]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 03 17:57:18 volumio volumio[7045]: errno: -111, Sep 03 17:57:18 volumio volumio[7045]: code: 'ECONNREFUSED', Sep 03 17:57:18 volumio volumio[7045]: syscall: 'connect', Sep 03 17:57:18 volumio volumio[7045]: address: '4.78.139.50', Sep 03 17:57:18 volumio volumio[7045]: port: 443 Sep 03 17:57:18 volumio volumio[7045]: } Sep 03 17:57:18 volumio volumio[7045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 17:57:19 volumio volumio[7045]: Starting albumart workers Sep 03 17:57:20 volumio sudo[7172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 17:56 Sep 03 17:57:20 volumio sudo[7172]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"