-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Mon 2025-04-14 17:09:04 +07. -- Apr 14 17:08:00 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:00 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 14 17:08:00 volumiokan volumio[875]: verbose: ControllerMpd::sendMpdCommand update Apr 14 17:08:00 volumiokan volumio[875]: info: sendMpdCommand update took 1 milliseconds Apr 14 17:08:04 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:08 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:11 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:08:11 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:08:11 volumiokan volumio[875]: info: Discovery: Getting this device information Apr 14 17:08:11 volumiokan volumio[875]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:11 volumiokan volumio[875]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:11 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:08:11 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 14 17:08:11 volumiokan volumio[875]: verbose: ControllerMpd::sendMpdCommand rescan Apr 14 17:08:11 volumiokan volumio[875]: info: sendMpdCommand rescan took 2 milliseconds Apr 14 17:08:12 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:16 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:19 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 14 17:08:19 volumiokan volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 14 17:08:19 volumiokan volumio[875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:08:19 volumiokan volumio[875]: Error: getaddrinfo -3007 Apr 14 17:08:19 volumiokan volumio[875]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 14 17:08:19 volumiokan volumio[875]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 14 17:08:19 volumiokan volumio[875]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 14 17:08:19 volumiokan volumio[875]: code: -3007, Apr 14 17:08:19 volumiokan volumio[875]: errno: -3007, Apr 14 17:08:19 volumiokan volumio[875]: syscall: 'getaddrinfo' Apr 14 17:08:19 volumiokan volumio[875]: } Apr 14 17:08:19 volumiokan volumio[875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:08:20 volumiokan sudo[12600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-14 17:07 Apr 14 17:08:20 volumiokan sudo[12600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:20 volumiokan sudo[12600]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:21 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 17:08:21 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 17:08:21 volumiokan go-librespot[1103]: time="2025-04-14T17:08:21+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Apr 14 17:08:21 volumiokan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 17:08:21 volumiokan systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 17:08:21 volumiokan systemd[1]: Started dynamicswap service. Apr 14 17:08:21 volumiokan systemd[1]: dynamicswap.service: Succeeded. Apr 14 17:08:21 volumiokan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 17:08:21 volumiokan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 14 17:08:21 volumiokan systemd[1]: Started dynamicswap service. Apr 14 17:08:21 volumiokan systemd[1]: Stopped Volumio Backend Module. Apr 14 17:08:21 volumiokan systemd[1]: Started Volumio Backend Module. Apr 14 17:08:21 volumiokan systemd[1]: dynamicswap.service: Succeeded. Apr 14 17:08:22 volumiokan volumio[12617]: info: ------------------------------------------- Apr 14 17:08:22 volumiokan volumio[12617]: info: ----- Volumio3 ---- Apr 14 17:08:22 volumiokan volumio[12617]: info: ------------------------------------------- Apr 14 17:08:22 volumiokan volumio[12617]: info: ----- System startup ---- Apr 14 17:08:22 volumiokan volumio[12617]: info: ------------------------------------------- Apr 14 17:08:23 volumiokan volumio[12617]: info: MYVOLUMIO Environment detected Apr 14 17:08:23 volumiokan volumio[12617]: info: Plugin folders cleanup Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning into folder /volumio/app/plugins/ Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category audio_interface Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category miscellanea Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category music_service Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category plugins.json Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category system_controller Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category user_interface Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning into folder /data/plugins/ Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category music_service Apr 14 17:08:23 volumiokan volumio[12617]: info: Scanning category user_interface Apr 14 17:08:23 volumiokan volumio[12617]: info: Plugin folders cleanup completed Apr 14 17:08:23 volumiokan volumio[12617]: info: ------------------------------------------- Apr 14 17:08:23 volumiokan volumio[12617]: info: ----- Core plugins startup ---- Apr 14 17:08:23 volumiokan volumio[12617]: info: ------------------------------------------- Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 17:08:23 volumiokan volumio[12617]: info: Adding plugin upnp to MyMusic Plugins Apr 14 17:08:23 volumiokan volumio[12617]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 17:08:23 volumiokan volumio[12617]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugins from folder /data/plugins/ Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "system"... Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "appearance"... Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "network"... Apr 14 17:08:23 volumiokan volumio[12617]: info: Refreshing Cached IP Addresses Apr 14 17:08:23 volumiokan sudo[12644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 17:08:23 volumiokan sudo[12644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:23 volumiokan sudo[12644]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:23 volumiokan sudo[12646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 17:08:23 volumiokan sudo[12646]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "services"... Apr 14 17:08:23 volumiokan sudo[12646]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "alsa_controller"... Apr 14 17:08:23 volumiokan sudo[12649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 14 17:08:23 volumiokan sudo[12649]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:23 volumiokan volumio[12617]: info: Hardware Mixer mismatch, hardware mixer type but softmixer selected, correcting Apr 14 17:08:23 volumiokan volumio[12617]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 17:08:23 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "wizard"... Apr 14 17:08:23 volumiokan volumio[12617]: info: Loading plugin "networkfs"... Apr 14 17:08:23 volumiokan volumio[12617]: info: Starting Udev Watcher for removable devices Apr 14 17:08:23 volumiokan volumio[12617]: info: Ignoring mount for partition: boot Apr 14 17:08:23 volumiokan volumio[12617]: info: Ignoring mount for partition: volumio Apr 14 17:08:23 volumiokan volumio[12617]: info: Ignoring mount for partition: volumio_data Apr 14 17:08:23 volumiokan volumio[12617]: info: Mounting Device USBHIRES Apr 14 17:08:23 volumiokan sudo[12662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USBHIRES -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 14 17:08:23 volumiokan sudo[12662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:26 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:26] [connect] Successful connection Apr 14 17:08:27 volumiokan sudo[12649]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:38 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:38] [connect] Successful connection Apr 14 17:08:41 volumiokan sudo[12662]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:41 volumiokan volumio[12617]: mount: /media/USBHIRES: /dev/sda1 already mounted on /media/USBHIRES. Apr 14 17:08:41 volumiokan volumio[12617]: error: Failed to mount USBHIRES: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/USBHIRES" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 14 17:08:41 volumiokan volumio[12617]: mount: /media/USBHIRES: /dev/sda1 already mounted on /media/USBHIRES. Apr 14 17:08:41 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "volumio_command_line_client"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "upnp"... Apr 14 17:08:41 volumiokan volumio[12617]: info: [1744625321414] Starting Upmpd Daemon Apr 14 17:08:41 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "my_music"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "mpd"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "upnp_browser"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "alarm-clock"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "airplay_emulation"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Starting Shairport Sync Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "last_100"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "webradio"... Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "i2s_dacs"... Apr 14 17:08:41 volumiokan volumio[12617]: info: I2S DAC not set, start Auto-detection Apr 14 17:08:41 volumiokan volumio[12617]: info: Loading plugin "volumiodiscovery"... Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** For more information see Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 17:08:42 volumiokan volumio[12617]: *** WARNING *** For more information see Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** For more information see Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 17:08:42 volumiokan node[12617]: *** WARNING *** For more information see Apr 14 17:08:42 volumiokan volumio[12617]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 17:08:42 volumiokan volumio[12617]: info: Discovery: Started advertising with name: Volumiokan Apr 14 17:08:42 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 17:08:42 volumiokan volumio[12617]: info: Loading plugin "spop"... Apr 14 17:08:42 volumiokan volumio[12617]: info: Loading plugin "youtube2"... Apr 14 17:08:43 volumiokan volumio[12617]: info: Loading plugin "ytmusic"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "now_playing"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "outputs"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "albumart"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Plugin example_plugin is not enabled Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "inputs"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "updater_comm"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Plugin mpdemulation is not enabled Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "rest_api"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "websocket"... Apr 14 17:08:44 volumiokan volumio[12617]: info: Starting Socket.io Server version 2.3.0 Apr 14 17:08:44 volumiokan volumio[12617]: info: Loading plugin "Systeminfo"... Apr 14 17:08:45 volumiokan volumio[12617]: Forking 3 albumart workers Apr 14 17:08:45 volumiokan volumio[12617]: info: Loading i18n strings for locale en Apr 14 17:08:45 volumiokan volumio[12617]: Updating browse sources language Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::initPlayerControls Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: Express server listening on port 3000 Apr 14 17:08:45 volumiokan volumio[12617]: [Metrics] WebUI: 23s 66.06ms Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::resetVolumioState Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::getcurrentVolume Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 14 17:08:45 volumiokan sudo[12706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 17:08:45 volumiokan sudo[12706]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:45 volumiokan systemd[1]: Stopping Music Player Daemon... Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::pushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioPushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::updateTrackBlock Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrackBlock Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:08:45 volumiokan volumio[12617]: info: Discovery: Getting this device information Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:08:45 volumiokan volumio[12617]: Starting albumart workers Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 14 17:08:45 volumiokan volumio[12617]: Starting albumart workers Apr 14 17:08:45 volumiokan volumio[12617]: Starting albumart workers Apr 14 17:08:45 volumiokan volumio[12617]: info: Reloading queue from file Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154:3000 from 192.168.1.214 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::pushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioPushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::setRepeat null single undefined Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::pushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioPushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::setRandom null Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreStateMachine::pushState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioPushState Apr 14 17:08:45 volumiokan volumio[12617]: info: Setting Device type: Raspberry PI Apr 14 17:08:45 volumiokan volumio[12617]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 14 17:08:45 volumiokan volumio[12617]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Apr 14 17:08:45 volumiokan volumio[12617]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Apr 14 17:08:45 volumiokan volumio[12617]: verbose: New Socket.io Connection to 192.168.1.154 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 10; MI 9 Build/QKQ1.190825.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.37 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:45 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:45 volumiokan volumio[12617]: info: Completed loading Core Plugins Apr 14 17:08:45 volumiokan volumio[12617]: info: Preparing to generate the ALSA configuration file Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 17:08:45 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 17:08:45 volumiokan volumio[12617]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 14 17:08:45 volumiokan volumio[12617]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 14 17:08:45 volumiokan volumio[12617]: info: Asound.conf file unchanged, so no further update is needed Apr 14 17:08:45 volumiokan volumio[12617]: info: Output device has changed, restarting MPD Apr 14 17:08:46 volumiokan sudo[12722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 17:08:46 volumiokan volumio[12617]: info: Output device has changed, restarting Shairport Sync Apr 14 17:08:46 volumiokan sudo[12722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan sudo[12722]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:46 volumiokan sudo[12723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 17:08:46 volumiokan sudo[12723]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:46 volumiokan volumio[12617]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 17:08:46 volumiokan volumio[12617]: info: ___________ START PLUGINS ___________ Apr 14 17:08:46 volumiokan volumio[12617]: info: ControllerMpd::onStart: Initializing MPD Apr 14 17:08:46 volumiokan volumio[12617]: info: Creating MPD Configuration file Apr 14 17:08:46 volumiokan sudo[12738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 17:08:46 volumiokan sudo[12738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 17:08:46 volumiokan volumio[12617]: info: [1744625326206] CoreMusicLibrary::Adding element Media Servers Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan sudo[12738]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:46 volumiokan sudo[12741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 17:08:46 volumiokan sudo[12741]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 17:08:46 volumiokan volumio[12617]: info: [1744625326338] CoreMusicLibrary::Adding element Last_100 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 17:08:46 volumiokan volumio[12617]: info: [1744625326342] CoreMusicLibrary::Adding element Webradio Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:08:46 volumiokan volumio[12617]: info: Initializing BBC Radios Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: Creating Spotify config file Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 17:08:46 volumiokan volumio[12617]: info: [1744625326421] CoreMusicLibrary::Adding element YouTube2 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan volumio[12617]: Cannot find translation for source YouTube2 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 17:08:46 volumiokan volumio[12617]: info: [1744625326427] CoreMusicLibrary::Adding element YouTube Music Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan volumio[12617]: Cannot find translation for source YouTube2 Apr 14 17:08:46 volumiokan volumio[12617]: Cannot find translation for source YouTube Music Apr 14 17:08:46 volumiokan volumio[12617]: info: [now-playing] ConfigUpdater: config is up to date. Apr 14 17:08:46 volumiokan volumio[12617]: info: Volumio Calling Home Apr 14 17:08:46 volumiokan volumio[12617]: info: [now-playing] App is listening on port 4004. Apr 14 17:08:46 volumiokan volumio[12617]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Apr 14 17:08:46 volumiokan volumio[12617]: info: Received Get System Info Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:08:46 volumiokan volumio[12617]: info: Discovery: Getting this device information Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetQueue Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreStateMachine::getQueue Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getQueue Apr 14 17:08:46 volumiokan volumio[12617]: info: Listing playlists Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 17:08:46 volumiokan volumio[12617]: info: Received Get System Info Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:08:46 volumiokan volumio[12617]: info: Discovery: Getting this device information Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:46 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:46 volumiokan volumio[12617]: info: MPD Permissions set Apr 14 17:08:46 volumiokan volumio[12617]: info: MPD Permissions set Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:46 volumiokan volumio[12617]: info: Spotify config file written Apr 14 17:08:46 volumiokan sudo[12779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 14 17:08:46 volumiokan sudo[12779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:46 volumiokan volumio[12617]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Apr 14 17:08:46 volumiokan systemd[1]: Stopping go-librespot Daemon... Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 14 17:08:46 volumiokan systemd[1]: go-librespot-daemon.service: Succeeded. Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan systemd[1]: Stopped go-librespot Daemon. Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan systemd[1]: Started go-librespot Daemon. Apr 14 17:08:46 volumiokan go-librespot[12781]: Librespot-go daemon starting... Apr 14 17:08:46 volumiokan sudo[12779]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:08:46 volumiokan go-librespot[12781]: time="2025-04-14T17:08:46+07:00" level=info msg="generated new device id: 58aba48cfe5390329a1af1c9083f438f3c09d620" Apr 14 17:08:46 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:46 volumiokan volumio[12617]: info: Volumio called home Apr 14 17:08:46 volumiokan volumio[12617]: info: Starting Shairport Sync Apr 14 17:08:47 volumiokan volumio[12617]: info: Starting Shairport Sync Apr 14 17:08:47 volumiokan sudo[12797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 17:08:47 volumiokan sudo[12797]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:47 volumiokan volumio[12617]: info: Starting Shairport Sync Apr 14 17:08:47 volumiokan systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 17:08:47 volumiokan systemd[1]: shairport-sync.service: Succeeded. Apr 14 17:08:47 volumiokan systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 17:08:47 volumiokan sudo[12802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 17:08:47 volumiokan sudo[12802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:47 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:47 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:47 volumiokan sudo[12804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 17:08:47 volumiokan systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 17:08:47 volumiokan sudo[12804]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:47 volumiokan sudo[12797]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:47 volumiokan volumio[12617]: info: Shairport-Sync Started Apr 14 17:08:47 volumiokan volumio[12617]: Error adding Membership: Error: addMembership EINVAL Apr 14 17:08:47 volumiokan go-librespot[12781]: time="2025-04-14T17:08:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 14 17:08:47 volumiokan go-librespot[12781]: time="2025-04-14T17:08:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 14 17:08:47 volumiokan go-librespot[12781]: time="2025-04-14T17:08:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 14 17:08:47 volumiokan systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 17:08:47 volumiokan systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 14 17:08:47 volumiokan systemd[1]: shairport-sync.service: Succeeded. Apr 14 17:08:47 volumiokan go-librespot[12781]: time="2025-04-14T17:08:47+07:00" level=debug msg="zeroconf server listening on port 42017" Apr 14 17:08:47 volumiokan systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 17:08:47 volumiokan systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 17:08:47 volumiokan sudo[12804]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:47 volumiokan sudo[12802]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:47 volumiokan volumio[12617]: info: Shairport-Sync Started Apr 14 17:08:47 volumiokan volumio[12617]: info: Shairport-Sync Started Apr 14 17:08:47 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:47 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:48 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:48 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:48 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:49 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:49 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:49 volumiokan volumio[12617]: info: go-librespot daemon successfully initialized Apr 14 17:08:50 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:50 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:51 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:51 volumiokan sudo[12814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 17:08:51 volumiokan sudo[12814]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:51 volumiokan sudo[12814]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:51 volumiokan sudo[12817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 17:08:51 volumiokan sudo[12817]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:51 volumiokan sudo[12817]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:51 volumiokan sudo[12820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 17:08:51 volumiokan sudo[12820]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 17:08:51 volumiokan sudo[12820]: pam_unix(sudo:session): session closed for user root Apr 14 17:08:51 volumiokan volumio[12617]: info: Upmpdcli Daemon Started Apr 14 17:08:51 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:52 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:52 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:52 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:52 volumiokan volumio[12617]: info: Initializing connection to go-librespot Websocket Apr 14 17:08:52 volumiokan go-librespot[12781]: time="2025-04-14T17:08:52+07:00" level=debug msg="new websocket client" Apr 14 17:08:52 volumiokan volumio[12617]: info: Connection to go-librespot Websocket established Apr 14 17:08:53 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:53 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:53] [connect] Successful connection Apr 14 17:08:53 volumiokan volumio-remote-updater[625]: [2025-04-14 17:08:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744625333 101 Apr 14 17:08:53 volumiokan volumio[12617]: 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: 27 Apr 14 17:08:53 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:54 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:54 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:55 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:55 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:08:55 volumiokan volumio[12617]: info: Getting Spotify volume Apr 14 17:08:56 volumiokan volumio[12617]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Apr 14 17:08:56 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:56 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:56 volumiokan volumio[12617]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Apr 14 17:08:56 volumiokan volumio[12617]: SPOTIFY: SPOTIFY VOLUME undefined Apr 14 17:08:56 volumiokan volumio[12617]: SPOTIFY: VOLUMIO VOLUME 89 Apr 14 17:08:56 volumiokan volumio[12617]: info: Aligning Spotify Volume to Volumio Volume Apr 14 17:08:56 volumiokan volumio[12617]: info: CoreCommandRouter::volumioGetState Apr 14 17:08:56 volumiokan volumio[12617]: info: CorePlayQueue::getTrack 0 Apr 14 17:08:56 volumiokan volumio[12617]: info: Setting Spotify Volume from Volumio: 89 Apr 14 17:08:56 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:08:57 volumiokan volumio[12617]: SPOTIFY: SETTING SPOTIFY VOLUME 89 Apr 14 17:08:57 volumiokan volumio[12617]: info: Sending Spotify command with payload to local API: /player/volume Apr 14 17:08:57 volumiokan volumio[12617]: error: Failed to send command to Spotify local API: /player/volume: Error: Internal Server Error Apr 14 17:09:00 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 17:09:04 volumiokan volumio[12617]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 14 17:09:04 volumiokan volumio[12617]: verbose: ControllerMpd::sendMpdCommand update Apr 14 17:09:04 volumiokan volumio[12617]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:09:04 volumiokan volumio[12617]: TypeError: Cannot read property 'then' of undefined Apr 14 17:09:04 volumiokan volumio[12617]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Apr 14 17:09:04 volumiokan volumio[12617]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Apr 14 17:09:04 volumiokan volumio[12617]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Apr 14 17:09:04 volumiokan volumio[12617]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1070:26) Apr 14 17:09:04 volumiokan volumio[12617]: at Socket.emit (events.js:315:20) Apr 14 17:09:04 volumiokan volumio[12617]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 14 17:09:04 volumiokan volumio[12617]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 17:09:04 volumiokan volumio[12617]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:09:04 volumiokan sudo[12873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-14 17:08 Apr 14 17:09:04 volumiokan sudo[12873]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"