-- Logs begin at Tue 2025-06-03 10:17:27 JST, end at Tue 2025-06-03 11:57:03 JST. -- Jun 03 11:56:00 volumio volumio[15410]: Forking 3 albumart workers Jun 03 11:56:01 volumio volumio[15410]: info: Loading i18n strings for locale ja Jun 03 11:56:01 volumio volumio[15410]: Updating browse sources language Jun 03 11:56:01 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::initPlayerControls Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: Express server listening on port 3000 Jun 03 11:56:02 volumio volumio[15410]: [Metrics] WebUI: 9s 603.08ms Jun 03 11:56:02 volumio volumio[15410]: info: CoreStateMachine::resetVolumioState Jun 03 11:56:02 volumio volumio[15410]: info: CoreStateMachine::getcurrentVolume Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:02 volumio volumio[15410]: Starting albumart workers Jun 03 11:56:02 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:02 volumio volumio[15410]: info: Cannot mount NAS MyMusic at system boot, trial number 2 ,retrying in 5 seconds Jun 03 11:56:02 volumio volumio[15410]: info: Volumio Network Manager: Network status updated: 0 Jun 03 11:56:02 volumio volumio[15410]: Starting albumart workers Jun 03 11:56:03 volumio volumio[15410]: Starting albumart workers Jun 03 11:56:03 volumio volumio[15410]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 03 11:56:03 volumio volumio[15410]: info: Reloading queue from file Jun 03 11:56:03 volumio volumio[15410]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:03 volumio volumio[15410]: info: CoreStateMachine::pushState Jun 03 11:56:03 volumio volumio[15410]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:03 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:03 volumio volumio[15410]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:03 volumio volumio[15410]: info: CoreStateMachine::updateTrackBlock Jun 03 11:56:03 volumio volumio[15410]: info: CorePlayQueue::getTrackBlock Jun 03 11:56:03 volumio volumio[15410]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:04 volumio volumio[15410]: info: CoreStateMachine::setRepeat true single undefined Jun 03 11:56:04 volumio volumio[15410]: info: CoreStateMachine::pushState Jun 03 11:56:04 volumio volumio[15410]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:04 volumio volumio[15410]: info: CoreStateMachine::setRandom true Jun 03 11:56:04 volumio volumio[15410]: info: CoreStateMachine::pushState Jun 03 11:56:04 volumio volumio[15410]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:04 volumio volumio[15410]: info: Setting Device type: Raspberry PI Jun 03 11:56:04 volumio volumio[15410]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 03 11:56:04 volumio volumio[15410]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jun 03 11:56:04 volumio volumio[15410]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 03 11:56:04 volumio volumio[15410]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:04 volumio volumio[15410]: info: CoreStateMachine::pushState Jun 03 11:56:04 volumio volumio[15410]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:04 volumio volumio[15410]: info: Completed loading Core Plugins Jun 03 11:56:04 volumio volumio[15410]: info: Preparing to generate the ALSA configuration file Jun 03 11:56:04 volumio volumio[15410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 03 11:56:04 volumio volumio[15410]: info: Reading ALSA contributions from plugins. Jun 03 11:56:04 volumio volumio[15410]: info: Asound.conf file unchanged, so no further update is needed Jun 03 11:56:04 volumio volumio[15410]: info: Output device has changed, restarting MPD Jun 03 11:56:04 volumio volumio[15410]: info: Output device has changed, restarting Shairport Sync Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:04 volumio sudo[15526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:04 volumio sudo[15526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:04 volumio sudo[15526]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:04 volumio sudo[15528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:04 volumio sudo[15528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:04 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 11:56:04 volumio volumio[15410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:04 volumio volumio[15410]: info: ___________ START PLUGINS ___________ Jun 03 11:56:04 volumio volumio[15410]: info: ControllerMpd::onStart: Initializing MPD Jun 03 11:56:04 volumio volumio[15410]: info: Creating MPD Configuration file Jun 03 11:56:04 volumio sudo[15535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:04 volumio sudo[15535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:04 volumio volumio[15410]: info: [1748919364960] CoreMusicLibrary::Adding element DLNAサーバー Jun 03 11:56:04 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:05 volumio sudo[15542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:05 volumio sudo[15542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:05 volumio sudo[15535]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:05 volumio volumio[15410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:05 volumio systemd[1]: mpd.service: Succeeded. Jun 03 11:56:05 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:05 volumio systemd[1]: Starting Music Player Daemon... Jun 03 11:56:05 volumio volumio[15410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:05 volumio volumio[15410]: info: [1748919365244] CoreMusicLibrary::Adding element Last_100 Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:05 volumio volumio[15410]: info: [1748919365252] CoreMusicLibrary::Adding element Webradio Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:05 volumio volumio[15410]: info: Initializing BBC Radios Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:05 volumio volumio[15410]: info: [1748919365363] CoreMusicLibrary::Adding element Mixcloud Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:05 volumio volumio[15410]: Cannot find translation for source Mixcloud Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:05 volumio volumio[15410]: info: [1748919365378] CoreMusicLibrary::Adding element LastFM Jun 03 11:56:05 volumio volumio[15410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:05 volumio volumio[15410]: Cannot find translation for source Mixcloud Jun 03 11:56:05 volumio volumio[15410]: Cannot find translation for source LastFM Jun 03 11:56:05 volumio volumio[15410]: info: [LastFM] scrobbler initiated! Jun 03 11:56:05 volumio volumio[15410]: info: [LastFM] extended logging: false Jun 03 11:56:05 volumio volumio[15410]: info: [LastFM] try scrobble stream/radio plays: true Jun 03 11:56:05 volumio volumio[15410]: info: [LastFM] Left init routine Jun 03 11:56:05 volumio volumio[15410]: info: [LastFM] Socket already connected: false Jun 03 11:56:05 volumio volumio[15410]: info: Volumio Calling Home Jun 03 11:56:05 volumio sudo[15551]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 11:56:05 volumio sudo[15551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:05 volumio sudo[15551]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:05 volumio volumio[15410]: Unhandled rejection Error: No sockets available, cannot start. Jun 03 11:56:05 volumio volumio[15410]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 03 11:56:05 volumio volumio[15410]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 03 11:56:05 volumio volumio[15410]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 03 11:56:05 volumio volumio[15410]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 03 11:56:05 volumio volumio[15410]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 03 11:56:05 volumio volumio[15410]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 03 11:56:05 volumio volumio[15410]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 03 11:56:05 volumio volumio[15410]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 03 11:56:05 volumio volumio[15410]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 03 11:56:05 volumio volumio[15410]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 03 11:56:05 volumio volumio[15410]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 03 11:56:05 volumio volumio[15410]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 03 11:56:05 volumio volumio[15410]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 03 11:56:05 volumio volumio[15410]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 03 11:56:05 volumio volumio[15410]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 03 11:56:05 volumio volumio[15410]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 03 11:56:05 volumio volumio[15410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:56:05 volumio volumio[15410]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jun 03 11:56:05 volumio volumio[15410]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 03 11:56:05 volumio volumio[15410]: errno: -16, Jun 03 11:56:05 volumio volumio[15410]: code: 'EBUSY', Jun 03 11:56:05 volumio volumio[15410]: syscall: 'getaddrinfo', Jun 03 11:56:05 volumio volumio[15410]: hostname: 'ws.audioscrobbler.com' Jun 03 11:56:05 volumio volumio[15410]: } Jun 03 11:56:05 volumio volumio[15410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:56:06 volumio sudo[15592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 11:55 Jun 03 11:56:06 volumio sudo[15592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:07 volumio sudo[15592]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 03 11:56:07 volumio sudo[15528]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:07 volumio sudo[15542]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 03 11:56:07 volumio systemd[1]: Started dynamicswap service. Jun 03 11:56:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 03 11:56:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 03 11:56:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Jun 03 11:56:07 volumio systemd[1]: Stopped Volumio Backend Module. Jun 03 11:56:07 volumio systemd[1]: Started Volumio Backend Module. Jun 03 11:56:07 volumio systemd[1]: Started dynamicswap service. Jun 03 11:56:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 03 11:56:08 volumio mpd[15567]: Jun 03 11:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 11:56:08 volumio volumio-remote-updater[581]: [2025-06-03 11:56:08] [info] asio async_connect error: system:111 (Connection refused) Jun 03 11:56:08 volumio volumio-remote-updater[581]: [2025-06-03 11:56:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 03 11:56:08 volumio volumio-remote-updater[581]: [2025-06-03 11:56:08] [error] handle_connect error: Underlying Transport Error Jun 03 11:56:10 volumio systemd[1]: Started Music Player Daemon. Jun 03 11:56:10 volumio volumio[15606]: info: ------------------------------------------- Jun 03 11:56:10 volumio volumio[15606]: info: ----- Volumio3 ---- Jun 03 11:56:10 volumio volumio[15606]: info: ------------------------------------------- Jun 03 11:56:10 volumio volumio[15606]: info: ----- System startup ---- Jun 03 11:56:10 volumio volumio[15606]: info: ------------------------------------------- Jun 03 11:56:11 volumio volumio[15606]: info: MYVOLUMIO Environment detected Jun 03 11:56:11 volumio volumio[15606]: info: Plugin folders cleanup Jun 03 11:56:11 volumio volumio[15606]: info: Scanning into folder /volumio/app/plugins/ Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category audio_interface Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category miscellanea Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category music_service Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category plugins.json Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category system_controller Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category user_interface Jun 03 11:56:11 volumio volumio[15606]: info: Scanning into folder /data/plugins/ Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category music_service Jun 03 11:56:11 volumio volumio[15606]: info: Scanning category user_interface Jun 03 11:56:11 volumio volumio[15606]: info: Plugin folders cleanup completed Jun 03 11:56:11 volumio volumio[15606]: info: ------------------------------------------- Jun 03 11:56:11 volumio volumio[15606]: info: ----- Core plugins startup ---- Jun 03 11:56:11 volumio volumio[15606]: info: ------------------------------------------- Jun 03 11:56:11 volumio volumio[15606]: info: Loading plugins from folder /volumio/app/plugins/ Jun 03 11:56:11 volumio volumio[15606]: info: Adding plugin upnp to MyMusic Plugins Jun 03 11:56:11 volumio volumio[15606]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 03 11:56:11 volumio volumio[15606]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 03 11:56:11 volumio volumio[15606]: info: Loading plugins from folder /data/plugins/ Jun 03 11:56:11 volumio volumio[15606]: info: Loading plugin "system"... Jun 03 11:56:11 volumio volumio[15606]: info: Loading plugin "appearance"... Jun 03 11:56:12 volumio wpa_supplicant[887]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="elecom2g-beff69" Jun 03 11:56:12 volumio wpa_supplicant[887]: wlan0: Failed to initiate sched scan Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "network"... Jun 03 11:56:13 volumio volumio[15606]: info: Refreshing Cached IP Addresses Jun 03 11:56:13 volumio sudo[15635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:56:13 volumio sudo[15635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:13 volumio sudo[15635]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "services"... Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "alsa_controller"... Jun 03 11:56:13 volumio sudo[15637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:56:13 volumio sudo[15637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:13 volumio sudo[15637]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:13 volumio sudo[15644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 03 11:56:13 volumio sudo[15644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:13 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "wizard"... Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "networkfs"... Jun 03 11:56:13 volumio volumio[15606]: info: Cannot mount NAS MyMusic at system boot, trial number 1 ,retrying in 5 seconds Jun 03 11:56:13 volumio volumio[15606]: info: Starting Udev Watcher for removable devices Jun 03 11:56:13 volumio volumio[15606]: info: Ignoring mount for partition: boot Jun 03 11:56:13 volumio volumio[15606]: info: Ignoring mount for partition: volumio Jun 03 11:56:13 volumio volumio[15606]: info: Ignoring mount for partition: volumio_data Jun 03 11:56:13 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:13 volumio volumio[15606]: info: Loading plugin "volumio_command_line_client"... Jun 03 11:56:14 volumio volumio[15606]: info: Loading plugin "upnp"... Jun 03 11:56:14 volumio volumio[15606]: info: [1748919374016] Starting Upmpd Daemon Jun 03 11:56:14 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:14 volumio volumio[15606]: info: Loading plugin "my_music"... Jun 03 11:56:14 volumio volumio[15606]: info: Loading plugin "mpd"... Jun 03 11:56:14 volumio sudo[15644]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:14 volumio volumio[15606]: info: Loading plugin "upnp_browser"... Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "alarm-clock"... Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "airplay_emulation"... Jun 03 11:56:15 volumio volumio[15606]: info: Starting Shairport Sync Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "last_100"... Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "webradio"... Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "i2s_dacs"... Jun 03 11:56:15 volumio volumio[15606]: info: I2S DAC not set, start Auto-detection Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "volumiodiscovery"... Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** For more information see Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:15 volumio volumio[15606]: *** WARNING *** For more information see Jun 03 11:56:15 volumio node[15606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:15 volumio node[15606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:15 volumio node[15606]: *** WARNING *** For more information see Jun 03 11:56:15 volumio node[15606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:15 volumio node[15606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:15 volumio node[15606]: *** WARNING *** For more information see Jun 03 11:56:15 volumio volumio[15606]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 03 11:56:15 volumio volumio[15606]: info: Discovery: Started advertising with name: Volumio Jun 03 11:56:15 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:15 volumio volumio[15606]: info: Loading plugin "mixcloud"... Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "outputs"... Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "albumart"... Jun 03 11:56:16 volumio volumio[15606]: info: Plugin example_plugin is not enabled Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "inputs"... Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "updater_comm"... Jun 03 11:56:16 volumio volumio[15606]: info: Plugin mpdemulation is not enabled Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "rest_api"... Jun 03 11:56:16 volumio volumio[15606]: info: Loading plugin "websocket"... Jun 03 11:56:17 volumio volumio[15606]: info: Starting Socket.io Server version 2.3.0 Jun 03 11:56:17 volumio volumio[15606]: info: Loading plugin "lastfm"... Jun 03 11:56:17 volumio volumio[15606]: Forking 3 albumart workers Jun 03 11:56:17 volumio wpa_supplicant[887]: wlan0: Trying to associate with SSID 'elecom2g-beff69' Jun 03 11:56:17 volumio dhcpcd[899]: wlan0: carrier acquired Jun 03 11:56:17 volumio wpa_supplicant[887]: wlan0: Associated with bc:5c:4c:be:ff:6a Jun 03 11:56:17 volumio wpa_supplicant[887]: wlan0: CTRL-EVENT-CONNECTED - Connection to bc:5c:4c:be:ff:6a completed [id=0 id_str=] Jun 03 11:56:17 volumio wpa_supplicant[887]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 03 11:56:17 volumio wpa_supplicant[887]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jun 03 11:56:17 volumio dhcpcd[899]: wlan0: IAID eb:48:9f:ea Jun 03 11:56:17 volumio dhcpcd[899]: wlan0: carrier lost Jun 03 11:56:18 volumio volumio[15606]: info: Loading i18n strings for locale ja Jun 03 11:56:18 volumio volumio[15606]: Updating browse sources language Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:18 volumio volumio-remote-updater[581]: [2025-06-03 11:56:18] [connect] Successful connection Jun 03 11:56:18 volumio dhcpcd[899]: wlan0: carrier acquired Jun 03 11:56:18 volumio dhcpcd[899]: wlan0: IAID eb:48:9f:ea Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::initPlayerControls Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: Express server listening on port 3000 Jun 03 11:56:18 volumio volumio[15606]: [Metrics] WebUI: 8s 838.06ms Jun 03 11:56:18 volumio dhcpcd[899]: wlan0: soliciting an IPv6 router Jun 03 11:56:18 volumio volumio[15606]: info: CoreStateMachine::resetVolumioState Jun 03 11:56:18 volumio dhcpcd[899]: wlan0: rebinding lease of 192.168.11.156 Jun 03 11:56:18 volumio volumio[15606]: info: CoreStateMachine::getcurrentVolume Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:18 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:18 volumio volumio[15606]: info: Volumio Network Manager: Network status updated: 0 Jun 03 11:56:18 volumio volumio-remote-updater[581]: [2025-06-03 11:56:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748919378 101 Jun 03 11:56:19 volumio volumio[15606]: 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: 1 Jun 03 11:56:19 volumio volumio[15606]: info: Cannot mount NAS MyMusic at system boot, trial number 2 ,retrying in 5 seconds Jun 03 11:56:19 volumio volumio[15606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 03 11:56:19 volumio volumio[15606]: info: Reloading queue from file Jun 03 11:56:19 volumio volumio[15606]: Starting albumart workers Jun 03 11:56:19 volumio volumio[15606]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::pushState Jun 03 11:56:19 volumio volumio[15606]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::updateTrackBlock Jun 03 11:56:19 volumio volumio[15606]: info: CorePlayQueue::getTrackBlock Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::setRepeat true single undefined Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::pushState Jun 03 11:56:19 volumio volumio[15606]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::setRandom true Jun 03 11:56:19 volumio volumio[15606]: info: CoreStateMachine::pushState Jun 03 11:56:19 volumio volumio[15606]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:19 volumio volumio[15606]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:19 volumio volumio[15606]: info: Setting Device type: Raspberry PI Jun 03 11:56:20 volumio volumio[15606]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 03 11:56:20 volumio volumio[15606]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jun 03 11:56:20 volumio volumio[15606]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 03 11:56:20 volumio volumio[15606]: Starting albumart workers Jun 03 11:56:20 volumio volumio[15606]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:20 volumio volumio[15606]: info: CoreStateMachine::pushState Jun 03 11:56:20 volumio volumio[15606]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:20 volumio volumio[15606]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:20 volumio volumio[15606]: info: Completed loading Core Plugins Jun 03 11:56:20 volumio volumio[15606]: info: Preparing to generate the ALSA configuration file Jun 03 11:56:20 volumio nmbd[760]: [2025/06/03 11:56:20.821376, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Jun 03 11:56:20 volumio nmbd[760]: reload_interfaces: No subnets to listen to. Waiting.. Jun 03 11:56:20 volumio volumio[15606]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 03 11:56:20 volumio volumio[15606]: info: Reading ALSA contributions from plugins. Jun 03 11:56:20 volumio volumio[15606]: Starting albumart workers Jun 03 11:56:20 volumio volumio[15606]: info: Asound.conf file unchanged, so no further update is needed Jun 03 11:56:20 volumio volumio[15606]: info: Output device has changed, restarting MPD Jun 03 11:56:21 volumio volumio[15606]: info: Output device has changed, restarting Shairport Sync Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:21 volumio sudo[15724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:21 volumio sudo[15724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:21 volumio sudo[15724]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:21 volumio sudo[15726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:21 volumio sudo[15726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:21 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 11:56:21 volumio systemd[1]: mpd.service: Succeeded. Jun 03 11:56:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 11:56:21 volumio volumio[15606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:21 volumio volumio[15606]: info: ___________ START PLUGINS ___________ Jun 03 11:56:21 volumio volumio[15606]: info: ControllerMpd::onStart: Initializing MPD Jun 03 11:56:21 volumio volumio[15606]: info: Creating MPD Configuration file Jun 03 11:56:21 volumio systemd[1]: Starting Music Player Daemon... Jun 03 11:56:21 volumio sudo[15742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:21 volumio sudo[15742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:21 volumio sudo[15742]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:21 volumio volumio[15606]: info: [1748919381675] CoreMusicLibrary::Adding element DLNAサーバー Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:21 volumio sudo[15745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:21 volumio sudo[15745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:21 volumio sudo[15740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 11:56:21 volumio sudo[15740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:21 volumio sudo[15740]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:21 volumio volumio[15606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:21 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 03 11:56:21 volumio systemd[1]: mpd.service: Succeeded. Jun 03 11:56:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 11:56:21 volumio systemd[1]: Starting Music Player Daemon... Jun 03 11:56:22 volumio volumio[15606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:22 volumio volumio[15606]: info: [1748919382103] CoreMusicLibrary::Adding element Last_100 Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:22 volumio volumio[15606]: info: [1748919382124] CoreMusicLibrary::Adding element Webradio Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:22 volumio volumio[15606]: info: Initializing BBC Radios Jun 03 11:56:22 volumio sudo[15763]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 11:56:22 volumio sudo[15763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:22 volumio sudo[15763]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:22 volumio volumio[15606]: info: [1748919382316] CoreMusicLibrary::Adding element Mixcloud Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:22 volumio volumio[15606]: Cannot find translation for source Mixcloud Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:22 volumio volumio[15606]: info: [1748919382330] CoreMusicLibrary::Adding element LastFM Jun 03 11:56:22 volumio volumio[15606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:22 volumio volumio[15606]: Cannot find translation for source Mixcloud Jun 03 11:56:22 volumio volumio[15606]: Cannot find translation for source LastFM Jun 03 11:56:22 volumio volumio[15606]: info: [LastFM] scrobbler initiated! Jun 03 11:56:22 volumio volumio[15606]: info: [LastFM] extended logging: false Jun 03 11:56:22 volumio volumio[15606]: info: [LastFM] try scrobble stream/radio plays: true Jun 03 11:56:22 volumio volumio[15606]: info: [LastFM] Left init routine Jun 03 11:56:22 volumio volumio[15606]: info: [LastFM] Socket already connected: false Jun 03 11:56:22 volumio volumio[15606]: info: Volumio Calling Home Jun 03 11:56:22 volumio volumio[15606]: Unhandled rejection Error: No sockets available, cannot start. Jun 03 11:56:22 volumio volumio[15606]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 03 11:56:22 volumio volumio[15606]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 03 11:56:22 volumio volumio[15606]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 03 11:56:22 volumio volumio[15606]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 03 11:56:22 volumio volumio[15606]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 03 11:56:22 volumio volumio[15606]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 03 11:56:22 volumio volumio[15606]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 03 11:56:22 volumio volumio[15606]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 03 11:56:22 volumio volumio[15606]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 03 11:56:22 volumio volumio[15606]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 03 11:56:22 volumio volumio[15606]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 03 11:56:22 volumio volumio[15606]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 03 11:56:22 volumio volumio[15606]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 03 11:56:22 volumio volumio[15606]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 03 11:56:22 volumio volumio[15606]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 03 11:56:22 volumio volumio[15606]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 03 11:56:22 volumio volumio[15606]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:56:22 volumio volumio[15606]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jun 03 11:56:22 volumio volumio[15606]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 03 11:56:22 volumio volumio[15606]: errno: -16, Jun 03 11:56:22 volumio volumio[15606]: code: 'EBUSY', Jun 03 11:56:22 volumio volumio[15606]: syscall: 'getaddrinfo', Jun 03 11:56:22 volumio volumio[15606]: hostname: 'ws.audioscrobbler.com' Jun 03 11:56:22 volumio volumio[15606]: } Jun 03 11:56:22 volumio volumio[15606]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:56:23 volumio dhcpcd[899]: wlan0: probing for an IPv4LL address Jun 03 11:56:23 volumio dhcpcd[899]: wlan0: DHCP lease expired Jun 03 11:56:23 volumio dhcpcd[899]: wlan0: soliciting a DHCP lease Jun 03 11:56:23 volumio sudo[15806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 11:55 Jun 03 11:56:23 volumio sudo[15806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:24 volumio sudo[15806]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 03 11:56:24 volumio volumio-remote-updater[581]: [2025-06-03 11:56:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 03 11:56:24 volumio volumio-remote-updater[581]: [2025-06-03 11:56:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 03 11:56:24 volumio sudo[15745]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:24 volumio sudo[15726]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 03 11:56:24 volumio systemd[1]: Started dynamicswap service. Jun 03 11:56:24 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 03 11:56:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 03 11:56:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Jun 03 11:56:24 volumio systemd[1]: Stopped Volumio Backend Module. Jun 03 11:56:24 volumio systemd[1]: Started Volumio Backend Module. Jun 03 11:56:24 volumio systemd[1]: Started dynamicswap service. Jun 03 11:56:24 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 03 11:56:24 volumio mpd[15767]: Jun 03 11:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 11:56:27 volumio systemd[1]: Started Music Player Daemon. Jun 03 11:56:27 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:27 volumio volumio[15833]: info: ----- Volumio3 ---- Jun 03 11:56:27 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:27 volumio volumio[15833]: info: ----- System startup ---- Jun 03 11:56:27 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:28 volumio dhcpcd[899]: wlan0: using IPv4LL address 169.254.199.168 Jun 03 11:56:28 volumio avahi-daemon[580]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.199.168. Jun 03 11:56:28 volumio avahi-daemon[580]: New relevant interface wlan0.IPv4 for mDNS. Jun 03 11:56:28 volumio dhcpcd[899]: wlan0: adding route to 169.254.0.0/16 Jun 03 11:56:28 volumio avahi-daemon[580]: Registering new address record for 169.254.199.168 on wlan0.IPv4. Jun 03 11:56:28 volumio dhcpcd[899]: wlan0: adding default route Jun 03 11:56:28 volumio volumio[15833]: info: MYVOLUMIO Environment detected Jun 03 11:56:28 volumio volumio[15833]: info: Plugin folders cleanup Jun 03 11:56:28 volumio volumio[15833]: info: Scanning into folder /volumio/app/plugins/ Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category audio_interface Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category miscellanea Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category music_service Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category plugins.json Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category system_controller Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category user_interface Jun 03 11:56:28 volumio volumio[15833]: info: Scanning into folder /data/plugins/ Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category music_service Jun 03 11:56:28 volumio volumio[15833]: info: Scanning category user_interface Jun 03 11:56:28 volumio volumio[15833]: info: Plugin folders cleanup completed Jun 03 11:56:28 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:28 volumio volumio[15833]: info: ----- Core plugins startup ---- Jun 03 11:56:28 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:28 volumio volumio[15833]: info: Loading plugins from folder /volumio/app/plugins/ Jun 03 11:56:28 volumio volumio[15833]: info: Adding plugin upnp to MyMusic Plugins Jun 03 11:56:28 volumio volumio[15833]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 03 11:56:28 volumio volumio[15833]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 03 11:56:28 volumio volumio[15833]: info: Loading plugins from folder /data/plugins/ Jun 03 11:56:28 volumio volumio[15833]: info: Loading plugin "system"... Jun 03 11:56:28 volumio volumio[15833]: info: Loading plugin "appearance"... Jun 03 11:56:29 volumio volumio-remote-updater[581]: [2025-06-03 11:56:29] [connect] Successful connection Jun 03 11:56:29 volumio ntpd[793]: Listen normally on 4 wlan0 169.254.199.168:123 Jun 03 11:56:29 volumio ntpd[793]: new interface(s) found: waking up resolver Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "network"... Jun 03 11:56:30 volumio volumio[15833]: info: Refreshing Cached IP Addresses Jun 03 11:56:30 volumio sudo[15873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:56:30 volumio sudo[15873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:30 volumio sudo[15875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:56:30 volumio sudo[15875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:30 volumio sudo[15875]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "services"... Jun 03 11:56:30 volumio sudo[15873]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "alsa_controller"... Jun 03 11:56:30 volumio sudo[15880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 03 11:56:30 volumio sudo[15880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:30 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "wizard"... Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "networkfs"... Jun 03 11:56:30 volumio volumio[15833]: info: Cannot mount NAS MyMusic at system boot, trial number 1 ,retrying in 5 seconds Jun 03 11:56:30 volumio volumio[15833]: info: Starting Udev Watcher for removable devices Jun 03 11:56:30 volumio volumio[15833]: info: Ignoring mount for partition: boot Jun 03 11:56:30 volumio volumio[15833]: info: Ignoring mount for partition: volumio Jun 03 11:56:30 volumio volumio[15833]: info: Ignoring mount for partition: volumio_data Jun 03 11:56:30 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "volumio_command_line_client"... Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "upnp"... Jun 03 11:56:30 volumio volumio[15833]: info: [1748919390891] Starting Upmpd Daemon Jun 03 11:56:30 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "my_music"... Jun 03 11:56:30 volumio volumio[15833]: info: Loading plugin "mpd"... Jun 03 11:56:31 volumio volumio[15833]: info: Loading plugin "upnp_browser"... Jun 03 11:56:31 volumio sudo[15880]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:31 volumio dhcpcd[899]: wlan0: offered 192.168.11.156 from 192.168.11.1 Jun 03 11:56:31 volumio volumio[15833]: info: Loading plugin "alarm-clock"... Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "airplay_emulation"... Jun 03 11:56:32 volumio volumio[15833]: info: Starting Shairport Sync Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "last_100"... Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "webradio"... Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "i2s_dacs"... Jun 03 11:56:32 volumio volumio[15833]: info: I2S DAC not set, start Auto-detection Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "volumiodiscovery"... Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** For more information see Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:32 volumio volumio[15833]: *** WARNING *** For more information see Jun 03 11:56:32 volumio node[15833]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:32 volumio node[15833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:32 volumio node[15833]: *** WARNING *** For more information see Jun 03 11:56:32 volumio node[15833]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:56:32 volumio volumio[15833]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 03 11:56:32 volumio node[15833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:56:32 volumio node[15833]: *** WARNING *** For more information see Jun 03 11:56:32 volumio volumio[15833]: info: Discovery: Started advertising with name: Volumio Jun 03 11:56:32 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:56:32 volumio volumio[15833]: info: Loading plugin "mixcloud"... Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "outputs"... Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "albumart"... Jun 03 11:56:33 volumio volumio[15833]: info: Plugin example_plugin is not enabled Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "inputs"... Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "updater_comm"... Jun 03 11:56:33 volumio volumio[15833]: info: Plugin mpdemulation is not enabled Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "rest_api"... Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "websocket"... Jun 03 11:56:33 volumio volumio[15833]: info: Starting Socket.io Server version 2.3.0 Jun 03 11:56:33 volumio volumio[15833]: info: Loading plugin "lastfm"... Jun 03 11:56:34 volumio volumio[15833]: Forking 3 albumart workers Jun 03 11:56:35 volumio dhcpcd[899]: wlan0: probing address 192.168.11.156/24 Jun 03 11:56:35 volumio volumio[15833]: info: Loading i18n strings for locale ja Jun 03 11:56:35 volumio volumio[15833]: Updating browse sources language Jun 03 11:56:35 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:35 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:35 volumio volumio[15833]: info: CoreCommandRouter::initPlayerControls Jun 03 11:56:35 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: Express server listening on port 3000 Jun 03 11:56:36 volumio volumio[15833]: [Metrics] WebUI: 9s 488.49ms Jun 03 11:56:36 volumio volumio[15833]: info: CoreStateMachine::resetVolumioState Jun 03 11:56:36 volumio volumio[15833]: info: CoreStateMachine::getcurrentVolume Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:36 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:36 volumio volumio[15833]: info: Cannot mount NAS MyMusic at system boot, trial number 2 ,retrying in 5 seconds Jun 03 11:56:36 volumio volumio[15833]: info: Volumio Network Manager: Network status updated: 2 Jun 03 11:56:36 volumio volumio[15833]: Starting albumart workers Jun 03 11:56:36 volumio volumio[15833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 03 11:56:37 volumio volumio[15833]: info: Reloading queue from file Jun 03 11:56:37 volumio volumio[15833]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::pushState Jun 03 11:56:37 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::updateTrackBlock Jun 03 11:56:37 volumio volumio[15833]: info: CorePlayQueue::getTrackBlock Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::setRepeat true single undefined Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::pushState Jun 03 11:56:37 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:37 volumio volumio[15833]: Starting albumart workers Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::setRandom true Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::pushState Jun 03 11:56:37 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:37 volumio volumio[15833]: Starting albumart workers Jun 03 11:56:37 volumio volumio[15833]: info: Setting Device type: Raspberry PI Jun 03 11:56:37 volumio volumio[15833]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 03 11:56:37 volumio volumio[15833]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jun 03 11:56:37 volumio volumio[15833]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 03 11:56:37 volumio volumio[15833]: info: VolumeController:: Volume=10 Mute =false Jun 03 11:56:37 volumio volumio[15833]: info: CoreStateMachine::pushState Jun 03 11:56:37 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::volumioPushState Jun 03 11:56:37 volumio volumio[15833]: info: Completed loading Core Plugins Jun 03 11:56:37 volumio volumio[15833]: info: Preparing to generate the ALSA configuration file Jun 03 11:56:37 volumio volumio[15833]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 03 11:56:37 volumio volumio[15833]: info: Reading ALSA contributions from plugins. Jun 03 11:56:37 volumio volumio[15833]: info: Asound.conf file unchanged, so no further update is needed Jun 03 11:56:37 volumio volumio[15833]: info: Output device has changed, restarting MPD Jun 03 11:56:37 volumio volumio[15833]: info: Output device has changed, restarting Shairport Sync Jun 03 11:56:37 volumio sudo[15952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:37 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:37 volumio sudo[15952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:38 volumio sudo[15952]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:38 volumio sudo[15955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:38 volumio sudo[15955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:38 volumio systemd[1]: Stopping Music Player Daemon... Jun 03 11:56:38 volumio volumio[15833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:38 volumio volumio[15833]: info: ___________ START PLUGINS ___________ Jun 03 11:56:38 volumio volumio[15833]: info: ControllerMpd::onStart: Initializing MPD Jun 03 11:56:38 volumio volumio[15833]: info: Creating MPD Configuration file Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:38 volumio volumio[15833]: info: [1748919398384] CoreMusicLibrary::Adding element DLNAサーバー Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:38 volumio sudo[15965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:56:38 volumio sudo[15965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:38 volumio sudo[15965]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:38 volumio systemd[1]: mpd.service: Succeeded. Jun 03 11:56:38 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 11:56:38 volumio sudo[15967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:56:38 volumio sudo[15967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:38 volumio systemd[1]: Starting Music Player Daemon... Jun 03 11:56:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 03 11:56:38 volumio systemd[1]: mpd.service: Succeeded. Jun 03 11:56:38 volumio systemd[1]: Stopped Music Player Daemon. Jun 03 11:56:38 volumio systemd[1]: Starting Music Player Daemon... Jun 03 11:56:38 volumio volumio[15833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:38 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:38 volumio sudo[15974]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 11:56:38 volumio sudo[15974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:38 volumio sudo[15974]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:38 volumio volumio[15833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:39 volumio volumio[15833]: info: [1748919399003] CoreMusicLibrary::Adding element Last_100 Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:39 volumio volumio[15833]: info: [1748919399024] CoreMusicLibrary::Adding element Webradio Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:39 volumio volumio[15833]: info: Initializing BBC Radios Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:39 volumio volumio[15833]: info: [1748919399266] CoreMusicLibrary::Adding element Mixcloud Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:39 volumio volumio[15833]: Cannot find translation for source Mixcloud Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:56:39 volumio volumio[15833]: info: [1748919399295] CoreMusicLibrary::Adding element LastFM Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:56:39 volumio volumio[15833]: Cannot find translation for source Mixcloud Jun 03 11:56:39 volumio volumio[15833]: Cannot find translation for source LastFM Jun 03 11:56:39 volumio volumio[15833]: info: [LastFM] scrobbler initiated! Jun 03 11:56:39 volumio volumio[15833]: info: [LastFM] extended logging: false Jun 03 11:56:39 volumio volumio[15833]: info: [LastFM] try scrobble stream/radio plays: true Jun 03 11:56:39 volumio volumio[15833]: info: [LastFM] Left init routine Jun 03 11:56:39 volumio volumio[15833]: info: [LastFM] Socket already connected: false Jun 03 11:56:39 volumio volumio[15833]: info: Volumio Calling Home Jun 03 11:56:39 volumio volumio[15833]: info: MPD Permissions set Jun 03 11:56:39 volumio volumio[15833]: info: MPD Permissions set Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:56:39 volumio volumio[15833]: info: Starting Shairport Sync Jun 03 11:56:39 volumio volumio[15833]: info: Starting Shairport Sync Jun 03 11:56:39 volumio volumio[15833]: info: Starting Shairport Sync Jun 03 11:56:39 volumio sudo[16018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:56:39 volumio sudo[16018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:39 volumio sudo[16020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:56:39 volumio sudo[16020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:39 volumio sudo[16024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:56:39 volumio sudo[16024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 11:56:40 volumio dhcpcd[899]: wlan0: leased 192.168.11.156 for 28800 seconds Jun 03 11:56:40 volumio avahi-daemon[580]: Registering new address record for 192.168.11.156 on wlan0.IPv4. Jun 03 11:56:40 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 11:56:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 11:56:40 volumio dhcpcd[899]: wlan0: adding route to 192.168.11.0/24 Jun 03 11:56:40 volumio dhcpcd[899]: wlan0: changing default route via 192.168.11.1 Jun 03 11:56:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 11:56:40 volumio sudo[16018]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:40 volumio sudo[16020]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:40 volumio volumio[15833]: info: Shairport-Sync Started Jun 03 11:56:40 volumio volumio[15833]: Error adding Membership: Error: addMembership EINVAL Jun 03 11:56:40 volumio volumio[15833]: info: Shairport-Sync Started Jun 03 11:56:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 03 11:56:40 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 03 11:56:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 03 11:56:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 03 11:56:40 volumio sudo[16024]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:40 volumio volumio[15833]: info: Shairport-Sync Started Jun 03 11:56:40 volumio avahi-daemon[580]: Withdrawing address record for 169.254.199.168 on wlan0. Jun 03 11:56:40 volumio avahi-daemon[580]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.199.168. Jun 03 11:56:40 volumio dhcpcd[899]: wlan0: deleting route to 169.254.0.0/16 Jun 03 11:56:40 volumio avahi-daemon[580]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.156. Jun 03 11:56:40 volumio dhcpcd[899]: wlan0: pid 899 deleted default route via 192.168.11.1 Jun 03 11:56:40 volumio sudo[16088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:56:40 volumio sudo[16088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:41 volumio sudo[16090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:56:41 volumio sudo[16088]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:41 volumio sudo[16090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:41 volumio sudo[16090]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:41 volumio sudo[16094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 03 11:56:41 volumio sudo[16094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:41 volumio sudo[16094]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:41 volumio volumio[15833]: info: Upmpdcli Daemon Started Jun 03 11:56:41 volumio volumio[15833]: info: Cannot mount NAS MyMusic at system boot, trial number 3 ,retrying in 5 seconds Jun 03 11:56:41 volumio mpd[15979]: Jun 03 11:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 11:56:41 volumio volumio-remote-updater[581]: [2025-06-03 11:56:41] [connect] Successful connection Jun 03 11:56:41 volumio volumio-remote-updater[581]: [2025-06-03 11:56:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748919401 101 Jun 03 11:56:41 volumio volumio[15833]: 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: 1 Jun 03 11:56:41 volumio ntpd[793]: Listen normally on 5 wlan0 192.168.11.156:123 Jun 03 11:56:41 volumio ntpd[793]: Deleting interface #4 wlan0, 169.254.199.168#123, interface stats: received=0, sent=10, dropped=0, active_time=12 secs Jun 03 11:56:41 volumio ntpd[793]: 202.181.103.212 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 194.0.5.123 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 139.162.81.45 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 64.176.59.83 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 45.76.211.39 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 162.159.200.123 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 45.77.20.103 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 133.130.121.141 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 172.237.12.44 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: 47.79.37.210 local addr 169.254.199.168 -> Jun 03 11:56:41 volumio ntpd[793]: new interface(s) found: waking up resolver Jun 03 11:56:42 volumio volumio[15833]: info: Discovery: adding d06bb55d-c9b2-4120-9eb2-2e1ea954575d Jun 03 11:56:42 volumio volumio[15833]: info: Discovery: Found device Volumio Jun 03 11:56:42 volumio volumio[15833]: info: CoreCommandRouter::volumioGetState Jun 03 11:56:42 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:42 volumio volumio[15833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 03 11:56:43 volumio volumio[15833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 03 11:56:43 volumio volumio[15833]: info: CoreCommandRouter::volumioGetState Jun 03 11:56:43 volumio volumio[15833]: info: CorePlayQueue::getTrack 0 Jun 03 11:56:44 volumio systemd[1]: Started Music Player Daemon. Jun 03 11:56:44 volumio sudo[15967]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:44 volumio sudo[15955]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:44 volumio volumio[15833]: info: Completed starting Core Plugins Jun 03 11:56:44 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:44 volumio volumio[15833]: info: ----- MyVolumio plugins startup ---- Jun 03 11:56:44 volumio volumio[15833]: info: ------------------------------------------- Jun 03 11:56:44 volumio volumio[15833]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 03 11:56:44 volumio kernel: TCP: request_sock_TCP: Possible SYN flooding on port [::]:6600. Sending cookies. Jun 03 11:56:44 volumio volumio[15833]: error: MPD error: The expression evaluated to a falsy value: Jun 03 11:56:44 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:44 volumio volumio[15833]: error: The expression evaluated to a falsy value: Jun 03 11:56:44 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:44 volumio volumio[15833]: info: MPD running with PID15979 Jun 03 11:56:44 volumio volumio[15833]: ,establishing connection Jun 03 11:56:44 volumio volumio[15833]: info: [LastFM] finished init: Authenticated successfully! Jun 03 11:56:44 volumio ntpd[793]: Soliciting pool server 240b:4009:272:9c06:def8:1b59:32ba:d60 Jun 03 11:56:46 volumio volumio[15833]: info: Cannot mount NAS MyMusic at system boot, trial number 4 ,retrying in 5 seconds Jun 03 11:56:47 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:48 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio volumio[15833]: error: MPD error: The expression evaluated to a falsy value: Jun 03 11:56:49 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:49 volumio volumio[15833]: error: The expression evaluated to a falsy value: Jun 03 11:56:49 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:49 volumio volumio[15833]: error: MPD error: The expression evaluated to a falsy value: Jun 03 11:56:49 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:49 volumio volumio[15833]: error: The expression evaluated to a falsy value: Jun 03 11:56:49 volumio volumio[15833]: assert.ok(self.idling) Jun 03 11:56:49 volumio kernel: net_ratelimit: 19 callbacks suppressed Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:49 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:50 volumio volumio[15833]: info: Volumio called home Jun 03 11:56:51 volumio volumio[15833]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin bluetooth to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin multiroom to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin metavolumio to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin cd_controller to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 03 11:56:53 volumio volumio[15833]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 03 11:56:54 volumio kernel: net_ratelimit: 135 callbacks suppressed Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:54 volumio volumio[15833]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 03 11:56:54 volumio volumio[15833]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 03 11:56:54 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:54 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:56:54 volumio volumio[15833]: info: Starting MyVolumio Remote Streaming Endpoints Jun 03 11:56:54 volumio volumio[15833]: info: MyVolumio login type: Token Jun 03 11:56:54 volumio volumio[15833]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 03 11:56:54 volumio volumio[15833]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 03 11:56:56 volumio volumio[15833]: info: Starting Streaming Service Transparent Proxy Jun 03 11:56:56 volumio volumio[15833]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 03 11:56:56 volumio volumio[15833]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 03 11:56:56 volumio volumio[15833]: info: Streaming services startup Jun 03 11:56:56 volumio volumio[15833]: info: Starting Streaming Daemon Jun 03 11:56:56 volumio volumio[15833]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 03 11:56:56 volumio sudo[16210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 03 11:56:56 volumio sudo[16210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:56:56 volumio sudo[16210]: pam_unix(sudo:session): session closed for user root Jun 03 11:56:56 volumio volumio[15833]: error: Cannot start Volumio Streaming Daemon Jun 03 11:56:56 volumio volumio[15833]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 03 11:56:56 volumio volumio[15833]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 03 11:56:57 volumio volumio[15833]: error: MyVolumio Custom Token format not valid, refreshing it Jun 03 11:56:58 volumio volumio[15833]: STREAMING PROXY: Starting server on port 3245 Jun 03 11:56:58 volumio volumio[15833]: Node JS runtime: 14 Jun 03 11:56:58 volumio volumio[15833]: info: MyVolumio login type: Token Jun 03 11:56:59 volumio volumio[15833]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 03 11:56:59 volumio kernel: net_ratelimit: 34 callbacks suppressed Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio kernel: nf_conntrack: nf_conntrack: table full, dropping packet Jun 03 11:56:59 volumio volumio[15833]: info: MyVolumio token set successfully Jun 03 11:56:59 volumio volumio[15833]: info: MYVOLUMIO: Adding device Jun 03 11:56:59 volumio volumio[15833]: info: MYVOLUMIO: Evaluating Server Jun 03 11:57:00 volumio volumio[15833]: info: MyVolumio status changed Jun 03 11:57:00 volumio volumio[15833]: info: Streaming services startup Jun 03 11:57:00 volumio volumio[15833]: info: Starting Streaming Daemon Jun 03 11:57:00 volumio volumio[15833]: info: Removing browser output: myVolumio user plan is not superstar Jun 03 11:57:00 volumio volumio[15833]: info: Removing audio output: Jun 03 11:57:00 volumio volumio[15833]: info: Stoppping Tunnel 1 Jun 03 11:57:00 volumio sudo[16241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 03 11:57:00 volumio sudo[16241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:57:00 volumio sudo[16241]: pam_unix(sudo:session): session closed for user root Jun 03 11:57:00 volumio volumio[15833]: error: Cannot start Volumio Streaming Daemon Jun 03 11:57:00 volumio volumio[15833]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 03 11:57:00 volumio volumio[15833]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 03 11:57:00 volumio sudo[16244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 03 11:57:00 volumio sudo[16244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 11:57:00 volumio sudo[16244]: pam_unix(sudo:session): session closed for user root Jun 03 11:57:00 volumio volumio[15833]: info: Remote SSH Stopped Jun 03 11:57:00 volumio volumio[15833]: verbose: New Socket.io Connection to 192.168.11.156:3000 from 192.168.11.156 UA: unknown Engine version: 3 Transport: websocket Total Clients: 4 Jun 03 11:57:01 volumio volumio[15833]: error: Failed to ping endpoint eu9.myvolumio.org : unknown error Jun 03 11:57:02 volumio volumio[15833]: info: Setting Geolocation for MyVolumio to as1 Jun 03 11:57:02 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:57:02 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:57:02 volumio volumio[15833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:57:02 volumio volumio[15833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:57:02 volumio volumio[15833]: Error: Unable to resolve or reject the same promise twice Jun 03 11:57:02 volumio volumio[15833]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 03 11:57:02 volumio volumio[15833]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jun 03 11:57:02 volumio volumio[15833]: at Socket.emit (events.js:327:22) Jun 03 11:57:02 volumio volumio[15833]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 03 11:57:02 volumio volumio[15833]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 03 11:57:02 volumio volumio[15833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:57:03 volumio sudo[16273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 11:56 Jun 03 11:57:03 volumio sudo[16273]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"