-- Logs begin at Mon 2024-05-13 15:40:55 CEST, end at Wed 2024-05-15 09:33:00 CEST. -- May 15 09:31:02 volumio volumio[29343]: info: Loading plugin "ytmusic"... May 15 09:31:04 volumio volumio[29343]: info: Loading plugin "gpio-buttons"... May 15 09:31:04 volumio volumio[29343]: info: GPIO-Buttons initialized May 15 09:31:04 volumio volumio[29343]: info: Loading plugin "now_playing"... May 15 09:31:05 volumio ntpd[877]: Soliciting pool server 193.123.56.220 May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "outputs"... May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "albumart"... May 15 09:31:07 volumio volumio[29343]: info: Plugin example_plugin is not enabled May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "inputs"... May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "updater_comm"... May 15 09:31:07 volumio volumio[29343]: info: Plugin mpdemulation is not enabled May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "rest_api"... May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "websocket"... May 15 09:31:07 volumio volumio[29343]: info: Loading plugin "80s80s"... May 15 09:31:08 volumio volumio[29343]: Forking 3 albumart workers May 15 09:31:08 volumio volumio[29343]: info: Applying required configuration parameters for plugin 80s80s May 15 09:31:08 volumio volumio[29343]: info: [1715758268399] [80s80s] API delay: 30 May 15 09:31:08 volumio volumio[29343]: info: Loading plugin "minidlna"... May 15 09:31:08 volumio volumio[29343]: info: Applying required configuration parameters for plugin minidlna May 15 09:31:08 volumio volumio[29343]: info: Loading plugin "motherearthradio"... May 15 09:31:09 volumio volumio[29343]: info: Applying required configuration parameters for plugin motherearthradio May 15 09:31:09 volumio volumio[29343]: info: [1715758269356] [MotherEarth] API delay: 5 May 15 09:31:09 volumio volumio[29343]: info: Loading plugin "personal_radio"... May 15 09:31:10 volumio volumio[29343]: Starting albumart workers May 15 09:31:10 volumio volumio[29343]: Starting albumart workers May 15 09:31:10 volumio volumio[29343]: Starting albumart workers May 15 09:31:10 volumio volumio[29343]: info: ControllerPersonalRadio::constructor May 15 09:31:10 volumio volumio[29343]: info: Loading plugin "podcast"... May 15 09:31:10 volumio volumio[29343]: info: ControllerPodcast::constructor May 15 09:31:11 volumio volumio[29343]: info: Loading plugin "radio_paradise"... May 15 09:31:11 volumio ntpd[877]: Soliciting pool server 5.79.108.34 May 15 09:31:11 volumio volumio[29343]: info: Applying required configuration parameters for plugin radio_paradise May 15 09:31:11 volumio volumio[29343]: info: [1715758271610] [RadioParadise] API delay: 5 May 15 09:31:11 volumio volumio[29343]: info: Loading plugin "backup_restore"... May 15 09:31:11 volumio volumio[29343]: info: Applying required configuration parameters for plugin backup_restore May 15 09:31:11 volumio volumio[29343]: info: Loading plugin "gpio_control"... May 15 09:31:12 volumio volumio[29343]: info: Applying required configuration parameters for plugin gpio_control May 15 09:31:12 volumio volumio[29343]: info: [GPIO_Control] Detected Xunlong Orange Pi One May 15 09:31:12 volumio volumio[29343]: info: [GPIO_Control] 40 GPIOs: false May 15 09:31:12 volumio volumio[29343]: info: [GPIO_Control] Initialized May 15 09:31:12 volumio volumio-remote-updater[589]: [2024-05-15 09:31:12] [connect] Successful connection May 15 09:31:12 volumio volumio[29343]: info: Loading i18n strings for locale nl May 15 09:31:12 volumio volumio[29343]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 15 09:31:12 volumio volumio[29343]: Updating browse sources language May 15 09:31:12 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::initPlayerControls May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:31:13 volumio volumio[29343]: Express server listening on port 3000 May 15 09:31:13 volumio volumio[29343]: [Metrics] WebUI: 29s 792.59ms May 15 09:31:13 volumio volumio[29343]: info: CoreStateMachine::resetVolumioState May 15 09:31:13 volumio volumio[29343]: info: CoreStateMachine::getcurrentVolume May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::volumioRetrievevolume May 15 09:31:13 volumio volumio[29343]: info: CoreStateMachine::pushState May 15 09:31:13 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::volumioPushState May 15 09:31:13 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:13 volumio volumio[29343]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds May 15 09:31:13 volumio sudo[29486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:31:13 volumio sudo[29486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:13 volumio sudo[29486]: pam_unix(sudo:session): session closed for user root May 15 09:31:13 volumio sudo[29489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:31:13 volumio sudo[29489]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:13 volumio volumio[29343]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 15 09:31:13 volumio volumio[29343]: info: Completed loading Core Plugins May 15 09:31:13 volumio volumio[29343]: info: Preparing to generate the ALSA configuration file May 15 09:31:13 volumio sudo[29489]: pam_unix(sudo:session): session closed for user root May 15 09:31:13 volumio volumio[29343]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 15 09:31:13 volumio volumio[29343]: wlan0 Interface doesn't support scanning. May 15 09:31:13 volumio volumio[29343]: info: Cannot use regular scanning, forcing with ap-force May 15 09:31:13 volumio sudo[29494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 15 09:31:13 volumio sudo[29494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:13 volumio sudo[29494]: pam_unix(sudo:session): session closed for user root May 15 09:31:13 volumio volumio[29343]: command failed: No such device (-19) May 15 09:31:13 volumio volumio[29343]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 15 09:31:13 volumio volumio[29343]: command failed: No such device (-19) May 15 09:31:13 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 May 15 09:31:13 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 May 15 09:31:13 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 May 15 09:31:13 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 May 15 09:31:13 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 May 15 09:31:14 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6 May 15 09:31:14 volumio volumio-remote-updater[589]: [2024-05-15 09:31:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715758272 101 May 15 09:31:14 volumio volumio[29343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 May 15 09:31:14 volumio volumio[29343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 May 15 09:31:14 volumio volumio[29343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 15 09:31:14 volumio volumio[29343]: info: Reloading queue from file May 15 09:31:14 volumio volumio[29343]: info: Asound.conf file unchanged, so no further update is needed May 15 09:31:14 volumio volumio[29343]: info: Output device has changed, restarting MPD May 15 09:31:14 volumio sudo[29503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:31:14 volumio sudo[29503]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:14 volumio sudo[29503]: pam_unix(sudo:session): session closed for user root May 15 09:31:14 volumio volumio[29343]: info: Output device has changed, restarting Shairport Sync May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:14 volumio sudo[29506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:31:14 volumio sudo[29506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:14 volumio systemd[1]: Stopping Music Player Daemon... May 15 09:31:14 volumio volumio[29343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:31:14 volumio volumio[29343]: info: ___________ START PLUGINS ___________ May 15 09:31:14 volumio volumio[29343]: info: ControllerMpd::onStart: Initializing MPD May 15 09:31:14 volumio volumio[29343]: info: Creating MPD Configuration file May 15 09:31:14 volumio sudo[29512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:31:14 volumio sudo[29512]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:14 volumio sudo[29512]: pam_unix(sudo:session): session closed for user root May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274602] CoreMusicLibrary::Adding element Media Servers May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio sudo[29515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:31:14 volumio sudo[29515]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:14 volumio volumio[29343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274884] CoreMusicLibrary::Adding element Last_100 May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274893] CoreMusicLibrary::Adding element Webradio May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 09:31:14 volumio volumio[29343]: info: Adding Calm Radio to Browse Sources May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274921] CoreMusicLibrary::Adding element Calm Radio May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:31:14 volumio volumio[29343]: info: Discovery: Getting this device information May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:14 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274963] CoreMusicLibrary::Adding element Jellyfin May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:14 volumio volumio[29343]: info: [jellyfin] Initialized plugin with device info: {"id":"a2317346-e94f-4d0b-982e-4bcd2c3fa530","host":"http://192.168.178.217","name":"volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"Bob Dylan","track":"Rainy Day Women # 12 & 35","albumart":"http://192.168.178.217/albumart?cacheid=524&web=Bob%20Dylan/Bob%20Dylan's%20Greatest%20Hits/extralarge&path=%2FNAS%2Fnas%2Fdsf%2FBob%20Dylan%20-%20Bob%20Dylan's%20Greatest%20Hits&metadata=false"}} May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:14 volumio volumio[29343]: info: [1715758274985] CoreMusicLibrary::Adding element YouTube2 May 15 09:31:14 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:14 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:14 volumio systemd[1]: mpd.service: Succeeded. May 15 09:31:15 volumio systemd[1]: Stopped Music Player Daemon. May 15 09:31:15 volumio systemd[1]: Starting Music Player Daemon... May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275098] CoreMusicLibrary::Adding element YouTube Music May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: info: GPIO-Buttons: Reading config and creating triggers... May 15 09:31:15 volumio volumio[29343]: info: [now-playing] ConfigUpdater: config is up to date. May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275166] CoreMusicLibrary::Adding element 80s80s Radio May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source 80s80s Radio May 15 09:31:15 volumio volumio[29343]: info: Loading i18n strings for locale nl May 15 09:31:15 volumio volumio[29343]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275274] CoreMusicLibrary::Adding element Mother Earth Radio May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source 80s80s Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Mother Earth Radio May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275441] CoreMusicLibrary::Adding element Personal Radio May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source 80s80s Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Mother Earth Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Personal Radio May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275480] CoreMusicLibrary::Adding element Podcast May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source 80s80s Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Mother Earth Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Personal Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Podcast May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:31:15 volumio volumio[29343]: info: [1715758275499] CoreMusicLibrary::Adding element Radio Paradise May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Calm Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Jellyfin May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube2 May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source YouTube Music May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source 80s80s Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Mother Earth Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Personal Radio May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Podcast May 15 09:31:15 volumio volumio[29343]: Cannot find translation for source Radio Paradise May 15 09:31:15 volumio volumio[29343]: info: [GPIO_Control] Reading config and creating GPIOs May 15 09:31:15 volumio volumio[29343]: info: [GPIO_Control] Will set GPIO 7 On when musicPlay May 15 09:31:15 volumio volumio[29343]: info: [GPIO_Control] Will set GPIO 3 Off when musicPause May 15 09:31:15 volumio volumio[29343]: info: Volumio Calling Home May 15 09:31:15 volumio volumio[29343]: info: GPIO-Buttons started May 15 09:31:15 volumio volumio[29343]: info: [GPIO_Control] GPIOs created May 15 09:31:15 volumio volumio[29343]: info: [now-playing] App is listening on port 4004. May 15 09:31:15 volumio volumio[29343]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 15 09:31:15 volumio volumio[29343]: info: CoreStateMachine::setRepeat null single undefined May 15 09:31:15 volumio volumio[29343]: info: CoreStateMachine::pushState May 15 09:31:15 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioPushState May 15 09:31:15 volumio volumio[29343]: info: CoreStateMachine::setRandom null May 15 09:31:15 volumio volumio[29343]: info: CoreStateMachine::pushState May 15 09:31:15 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:15 volumio volumio[29343]: info: CoreCommandRouter::volumioPushState May 15 09:31:16 volumio volumio[29343]: info: minidlna: Found miniDLNA version 1.2.1 May 15 09:31:16 volumio volumio[29343]: info: minidlna: Starting minidlna.service May 15 09:31:16 volumio volumio[29343]: info: MPD Permissions set May 15 09:31:16 volumio volumio[29343]: info: MPD Permissions set May 15 09:31:16 volumio sudo[29531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 15 09:31:16 volumio sudo[29531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:16 volumio sudo[29534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 15 09:31:16 volumio sudo[29534]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:16 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 15 09:31:16 volumio sudo[29531]: pam_unix(sudo:session): session closed for user root May 15 09:31:16 volumio sudo[29534]: pam_unix(sudo:session): session closed for user root May 15 09:31:16 volumio volumio[29343]: info: Volumio called home May 15 09:31:16 volumio volumio[29343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 May 15 09:31:17 volumio volumio[29343]: info: Upmpdcli Daemon Started May 15 09:31:17 volumio volumio[29343]: info: minidlna: systemctl start minidlna.service succeeded. May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:17 volumio volumio[29343]: info: Discovery: adding a2317346-e94f-4d0b-982e-4bcd2c3fa530 May 15 09:31:17 volumio volumio[29343]: info: Discovery: Found device volumio May 15 09:31:17 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:17 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:17 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 11 May 15 09:31:18 volumio volumio[29343]: (node:29343) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 15 09:31:18 volumio volumio[29343]: (Use `node --trace-deprecation ...` to show where the warning was created) May 15 09:31:18 volumio volumio[29343]: (node:29343) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 15 09:31:18 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:18 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:18 volumio volumio[29343]: info: Starting Shairport Sync May 15 09:31:18 volumio volumio[29343]: info: Starting Shairport Sync May 15 09:31:18 volumio sudo[29555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:31:18 volumio sudo[29555]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:31:18 volumio volumio[29343]: info: Starting Shairport Sync May 15 09:31:18 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:31:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:31:18 volumio sudo[29558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:31:18 volumio sudo[29558]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:31:18 volumio sudo[29555]: pam_unix(sudo:session): session closed for user root May 15 09:31:18 volumio sudo[29562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:31:18 volumio sudo[29562]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:31:18 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:31:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:31:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:31:18 volumio sudo[29558]: pam_unix(sudo:session): session closed for user root May 15 09:31:18 volumio sudo[29562]: pam_unix(sudo:session): session closed for user root May 15 09:31:18 volumio mpd[29523]: May 15 09:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 09:31:18 volumio volumio[29343]: info: Cannot mount NAS nas at system boot, trial number 3 ,retrying in 5 seconds May 15 09:31:18 volumio volumio[29343]: info: Shairport-Sync Started May 15 09:31:18 volumio volumio[29343]: Error adding Membership: Error: addMembership EINVAL May 15 09:31:18 volumio volumio[29343]: info: Shairport-Sync Started May 15 09:31:18 volumio volumio[29343]: info: Shairport-Sync Started May 15 09:31:19 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:19 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:19 volumio volumio[29343]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 15 09:31:19 volumio volumio[29343]: info: CoreCommandRouter::volumioRetrievevolume May 15 09:31:19 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:19 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:19 volumio volumio[29343]: info: CoreStateMachine::pushState May 15 09:31:19 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:19 volumio volumio[29343]: info: CoreCommandRouter::volumioPushState May 15 09:31:19 volumio volumio[29343]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: May 15 09:31:19 volumio volumio[29343]: (TypeError) Cannot read property 'vol' of undefined May 15 09:31:19 volumio volumio[29343]: TypeError: Cannot read property 'vol' of undefined May 15 09:31:19 volumio volumio[29343]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) May 15 09:31:19 volumio volumio[29343]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 15 09:31:19 volumio volumio[29343]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) May 15 09:31:19 volumio volumio[29343]: at async /data/plugins/music_service/ytcr/dist/index.js:310:13 May 15 09:31:22 volumio systemd[1]: Started Music Player Daemon. May 15 09:31:22 volumio sudo[29506]: pam_unix(sudo:session): session closed for user root May 15 09:31:22 volumio sudo[29515]: pam_unix(sudo:session): session closed for user root May 15 09:31:22 volumio volumio[29343]: info: Completed starting Core Plugins May 15 09:31:22 volumio volumio[29343]: info: ------------------------------------------- May 15 09:31:22 volumio volumio[29343]: info: ----- MyVolumio plugins startup ---- May 15 09:31:22 volumio volumio[29343]: info: ------------------------------------------- May 15 09:31:22 volumio volumio[29343]: info: [MyVolumio PluginManager] Fetching plans data.... May 15 09:31:22 volumio volumio[29343]: error: MPD error: The expression evaluated to a falsy value: May 15 09:31:22 volumio volumio[29343]: assert.ok(self.idling) May 15 09:31:22 volumio volumio[29343]: error: The expression evaluated to a falsy value: May 15 09:31:22 volumio volumio[29343]: assert.ok(self.idling) May 15 09:31:22 volumio volumio[29343]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 12 May 15 09:31:22 volumio volumio[29343]: info: MPD running with PID29523 May 15 09:31:22 volumio volumio[29343]: ,establishing connection May 15 09:31:22 volumio volumio[29343]: error: updateQueue error: null May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetVisibleSources May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:22 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:22 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 09:31:22 volumio volumio[29343]: info: Received Get System Info May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:31:22 volumio volumio[29343]: info: Discovery: Getting this device information May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:22 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetState May 15 09:31:22 volumio volumio[29343]: info: CorePlayQueue::getTrack 0 May 15 09:31:22 volumio volumio[29343]: info: Listing playlists May 15 09:31:22 volumio volumio[29343]: info: CoreCommandRouter::volumioGetQueue May 15 09:31:22 volumio volumio[29343]: info: CoreStateMachine::getQueue May 15 09:31:22 volumio volumio[29343]: info: CorePlayQueue::getQueue May 15 09:31:22 volumio volumio[29343]: error: updateQueue error: null May 15 09:31:23 volumio volumio[29343]: info: Cannot mount NAS nas at system boot, trial number 4 ,retrying in 5 seconds May 15 09:31:28 volumio volumio[29343]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 09:31:31 volumio volumio[29343]: info: Adding plugin bluetooth to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: Adding plugin multiroom to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: Adding plugin metavolumio to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: Adding plugin cd_controller to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: Adding plugin smart_inputs to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: Adding plugin tidalconnect to MyMusic Plugins May 15 09:31:31 volumio volumio[29343]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 09:31:33 volumio volumio[29343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 09:31:33 volumio volumio[29343]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 09:31:33 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:33 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:33 volumio volumio[29343]: info: Starting MyVolumio Remote Streaming Endpoints May 15 09:31:33 volumio volumio[29343]: info: MyVolumio login type: Token May 15 09:31:33 volumio volumio[29343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 09:31:33 volumio volumio[29343]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 15 09:31:35 volumio volumio[29343]: info: Starting Streaming Service Transparent Proxy May 15 09:31:35 volumio volumio[29343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 15 09:31:35 volumio volumio[29343]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 15 09:31:35 volumio volumio[29343]: info: Streaming services startup May 15 09:31:35 volumio volumio[29343]: info: Starting Streaming Daemon May 15 09:31:36 volumio sudo[29582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 09:31:36 volumio sudo[29582]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:31:36 volumio sudo[29582]: pam_unix(sudo:session): session closed for user root May 15 09:31:36 volumio volumio[29343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 15 09:31:36 volumio volumio[29343]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 15 09:31:36 volumio volumio[29343]: error: Cannot start Volumio Streaming Daemon May 15 09:31:36 volumio volumio[29343]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 09:31:36 volumio volumio[29343]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 09:31:37 volumio volumio[29343]: error: MyVolumio Custom Token format not valid, refreshing it May 15 09:31:39 volumio volumio[29343]: STREAMING PROXY: Starting server on port 3245 May 15 09:31:39 volumio volumio[29343]: Node JS runtime: 14 May 15 09:31:41 volumio volumio[29343]: info: MyVolumio login type: Token May 15 09:31:45 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 09:31:45 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:45 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 09:31:46 volumio volumio-remote-updater[589]: No test mode May 15 09:31:46 volumio volumio-remote-updater[589]: No alpha test mode May 15 09:31:46 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 15 09:31:48 volumio volumio[29343]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.666","updateavailable":true} May 15 09:31:48 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 15 09:31:51 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 09:31:52 volumio volumio[29343]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::volumioGetBrowseSources May 15 09:31:54 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 09:31:55 volumio volumio[29343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 15 09:31:56 volumio volumio[29343]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 15 09:31:56 volumio volumio[29343]: info: Completed starting MyVolumio Plugin May 15 09:31:56 volumio volumio[29343]: info: BOOT COMPLETED May 15 09:31:56 volumio volumio[29343]: [Metrics] CommandRouter: 71s 824.52ms May 15 09:31:56 volumio volumio[29343]: info: CoreCommandRouter::volumiosetStartupVolume May 15 09:31:56 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:31:56 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:31:56 volumio volumio[29343]: info: CoreCommandRouter::Close All Modals sent May 15 09:31:56 volumio volumio[29343]: info: CoreCommandRouter::Close All Modals sent May 15 09:31:57 volumio ntpd[877]: Soliciting pool server 193.148.248.179 May 15 09:31:57 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 15 09:31:57 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:31:57 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 15 09:31:57 volumio volumio[29343]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 15 09:31:58 volumio volumio[29343]: info: MyVolumio token set successfully May 15 09:31:58 volumio volumio[29343]: info: MYVOLUMIO: Adding device May 15 09:31:58 volumio volumio[29343]: info: MYVOLUMIO: Evaluating Server May 15 09:31:58 volumio volumio[29343]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 15 09:31:58 volumio volumio[29343]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 15 09:31:58 volumio volumio[29343]: aplay: main:828: audio open error: No such device May 15 09:32:02 volumio volumio[29343]: info: MyVolumio status changed May 15 09:32:02 volumio volumio[29343]: info: Streaming services startup May 15 09:32:02 volumio volumio[29343]: info: Starting Streaming Daemon May 15 09:32:02 volumio volumio[29343]: info: Removing browser output: myVolumio user plan is not superstar May 15 09:32:02 volumio volumio[29343]: info: Removing audio output: May 15 09:32:02 volumio volumio[29343]: info: Stoppping Tunnel 1 May 15 09:32:02 volumio sudo[29616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 09:32:02 volumio sudo[29616]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:02 volumio sudo[29616]: pam_unix(sudo:session): session closed for user root May 15 09:32:02 volumio sudo[29619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 15 09:32:02 volumio sudo[29619]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:02 volumio sudo[29619]: pam_unix(sudo:session): session closed for user root May 15 09:32:03 volumio ntpd[877]: Soliciting pool server 2a01:b2e0:2::61 May 15 09:32:03 volumio volumio[29343]: <--- Last few GCs ---> May 15 09:32:03 volumio volumio[29343]: [29343:0x464d008] 82074 ms: Scavenge 202.4 (215.5) -> 202.0 (215.5) MB, 6.2 / 0.0 ms (average mu = 0.899, current mu = 0.912) allocation failure May 15 09:32:03 volumio volumio[29343]: [29343:0x464d008] 82256 ms: Scavenge 221.2 (234.7) -> 221.1 (234.7) MB, 3.3 / 0.0 ms (average mu = 0.899, current mu = 0.912) allocation failure May 15 09:32:03 volumio volumio[29343]: [29343:0x464d008] 82281 ms: Scavenge (reduce) 221.2 (234.9) -> 221.2 (234.9) MB, 6.4 / 0.0 ms (average mu = 0.899, current mu = 0.912) allocation failure May 15 09:32:03 volumio volumio[29343]: <--- JS stacktrace ---> May 15 09:32:03 volumio volumio[29343]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory May 15 09:32:04 volumio volumio-remote-updater[589]: [2024-05-15 09:32:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 15 09:32:04 volumio volumio-remote-updater[589]: [2024-05-15 09:32:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 15 09:32:04 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT May 15 09:32:04 volumio systemd[1]: volumio.service: Failed with result 'signal'. May 15 09:32:04 volumio systemd[1]: Started dynamicswap service. May 15 09:32:04 volumio systemd[1]: dynamicswap.service: Succeeded. May 15 09:32:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 15 09:32:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 15 09:32:04 volumio systemd[1]: Started dynamicswap service. May 15 09:32:04 volumio systemd[1]: Stopped Volumio Backend Module. May 15 09:32:04 volumio systemd[1]: Started Volumio Backend Module. May 15 09:32:04 volumio systemd[1]: dynamicswap.service: Succeeded. May 15 09:32:08 volumio volumio[29628]: info: ------------------------------------------- May 15 09:32:08 volumio volumio[29628]: info: ----- Volumio3 ---- May 15 09:32:08 volumio volumio[29628]: info: ------------------------------------------- May 15 09:32:08 volumio volumio[29628]: info: ----- System startup ---- May 15 09:32:08 volumio volumio[29628]: info: ------------------------------------------- May 15 09:32:09 volumio volumio-remote-updater[589]: [2024-05-15 09:32:09] [connect] Successful connection May 15 09:32:10 volumio volumio[29628]: info: MYVOLUMIO Environment detected May 15 09:32:10 volumio volumio[29628]: info: Plugin folders cleanup May 15 09:32:10 volumio volumio[29628]: info: Scanning into folder /volumio/app/plugins/ May 15 09:32:10 volumio volumio[29628]: info: Scanning category audio_interface May 15 09:32:10 volumio volumio[29628]: info: Scanning category miscellanea May 15 09:32:10 volumio volumio[29628]: info: Scanning category music_service May 15 09:32:10 volumio volumio[29628]: info: Scanning category plugins.json May 15 09:32:10 volumio volumio[29628]: info: Scanning category system_controller May 15 09:32:10 volumio volumio[29628]: info: Scanning category user_interface May 15 09:32:10 volumio volumio[29628]: info: Scanning into folder /data/plugins/ May 15 09:32:10 volumio volumio[29628]: info: Scanning category music_service May 15 09:32:10 volumio volumio[29628]: info: Scanning category system_controller May 15 09:32:10 volumio volumio[29628]: info: Scanning category system_hardware May 15 09:32:10 volumio volumio[29628]: info: Scanning category user_interface May 15 09:32:10 volumio volumio[29628]: info: Plugin folders cleanup completed May 15 09:32:10 volumio volumio[29628]: info: ------------------------------------------- May 15 09:32:10 volumio volumio[29628]: info: ----- Core plugins startup ---- May 15 09:32:10 volumio volumio[29628]: info: ------------------------------------------- May 15 09:32:10 volumio volumio[29628]: info: Loading plugins from folder /volumio/app/plugins/ May 15 09:32:10 volumio volumio[29628]: info: Adding plugin upnp to MyMusic Plugins May 15 09:32:10 volumio volumio[29628]: info: Adding plugin airplay_emulation to MyMusic Plugins May 15 09:32:10 volumio volumio[29628]: info: Adding plugin upnp_browser to MyMusic Plugins May 15 09:32:10 volumio volumio[29628]: info: Loading plugins from folder /data/plugins/ May 15 09:32:10 volumio volumio[29628]: info: Loading plugin "system"... May 15 09:32:10 volumio volumio[29628]: info: Loading plugin "appearance"... May 15 09:32:12 volumio ntpd[877]: Soliciting pool server 206.189.8.121 May 15 09:32:13 volumio volumio[29628]: info: Loading plugin "network"... May 15 09:32:13 volumio volumio[29628]: info: Refreshing Cached IP Addresses May 15 09:32:13 volumio sudo[29697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:32:13 volumio sudo[29697]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:13 volumio sudo[29699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:32:13 volumio sudo[29697]: pam_unix(sudo:session): session closed for user root May 15 09:32:13 volumio sudo[29699]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:13 volumio volumio[29628]: info: Loading plugin "services"... May 15 09:32:13 volumio volumio[29628]: info: Loading plugin "alsa_controller"... May 15 09:32:13 volumio sudo[29702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 15 09:32:13 volumio sudo[29702]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:13 volumio sudo[29699]: pam_unix(sudo:session): session closed for user root May 15 09:32:13 volumio sudo[29702]: pam_unix(sudo:session): session closed for user root May 15 09:32:13 volumio volumio[29628]: warn: Unable to locate the audio output device Audiolab 2.0. Please configure a valid output device. May 15 09:32:13 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 09:32:13 volumio volumio[29628]: info: Loading plugin "wizard"... May 15 09:32:13 volumio volumio[29628]: info: Loading plugin "networkfs"... May 15 09:32:14 volumio volumio[29628]: info: Cannot mount NAS nas at system boot, trial number 1 ,retrying in 5 seconds May 15 09:32:14 volumio volumio[29628]: info: Starting Udev Watcher for removable devices May 15 09:32:14 volumio volumio[29628]: info: Ignoring mount for partition: boot May 15 09:32:14 volumio volumio[29628]: info: Ignoring mount for partition: volumio May 15 09:32:14 volumio volumio[29628]: info: Ignoring mount for partition: volumio_data May 15 09:32:14 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 09:32:14 volumio volumio[29628]: info: Loading plugin "volumio_command_line_client"... May 15 09:32:14 volumio volumio[29628]: info: Loading plugin "upnp"... May 15 09:32:14 volumio volumio[29628]: info: [1715758334155] Starting Upmpd Daemon May 15 09:32:14 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 09:32:14 volumio volumio[29628]: info: Loading plugin "my_music"... May 15 09:32:14 volumio volumio[29628]: info: Loading plugin "mpd"... May 15 09:32:15 volumio volumio[29628]: info: Loading plugin "upnp_browser"... May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "alarm-clock"... May 15 09:32:16 volumio ntpd[877]: Soliciting pool server 45.138.55.61 May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "airplay_emulation"... May 15 09:32:16 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "last_100"... May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "webradio"... May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "i2s_dacs"... May 15 09:32:16 volumio volumio[29628]: info: I2S DAC not set, start Auto-detection May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "volumiodiscovery"... May 15 09:32:16 volumio volumio[29628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 09:32:16 volumio volumio[29628]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 09:32:16 volumio node[29628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 15 09:32:16 volumio volumio[29628]: *** WARNING *** For more information see May 15 09:32:16 volumio volumio[29628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 09:32:16 volumio volumio[29628]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 09:32:16 volumio volumio[29628]: *** WARNING *** For more information see May 15 09:32:16 volumio node[29628]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 09:32:16 volumio node[29628]: *** WARNING *** For more information see May 15 09:32:16 volumio node[29628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 15 09:32:16 volumio node[29628]: *** WARNING *** Please fix your application to use the native API of Avahi! May 15 09:32:16 volumio node[29628]: *** WARNING *** For more information see May 15 09:32:16 volumio volumio[29628]: info: Applying required configuration parameters for plugin volumiodiscovery May 15 09:32:16 volumio volumio[29628]: info: Discovery: Started advertising with name: volumio May 15 09:32:16 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 09:32:16 volumio volumio[29628]: info: Loading plugin "calmradio"... May 15 09:32:18 volumio volumio[29628]: info: Loading plugin "jellyfin"... May 15 09:32:21 volumio volumio[29628]: info: Loading plugin "youtube2"... May 15 09:32:21 volumio volumio-remote-updater[589]: [2024-05-15 09:32:21] [connect] Successful connection May 15 09:32:24 volumio volumio[29628]: info: Loading plugin "ytcr"... May 15 09:32:28 volumio volumio[29628]: info: Loading plugin "ytmusic"... May 15 09:32:30 volumio volumio[29628]: info: Loading plugin "gpio-buttons"... May 15 09:32:31 volumio volumio[29628]: info: GPIO-Buttons initialized May 15 09:32:31 volumio volumio[29628]: info: Loading plugin "now_playing"... May 15 09:32:33 volumio kernel: usb 4-1: new high-speed USB device number 9 using ehci-platform May 15 09:32:34 volumio kernel: usb 4-1: New USB device found, idVendor=2622, idProduct=0041, bcdDevice= 1.40 May 15 09:32:34 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 15 09:32:34 volumio kernel: usb 4-1: Product: Audiolab 2.0 May 15 09:32:34 volumio kernel: usb 4-1: Manufacturer: Audiolab May 15 09:32:34 volumio kernel: usb 4-1: SerialNumber: 0 May 15 09:32:34 volumio kernel: usb 4-1: 1:3 : unsupported format bits 0x100000000 May 15 09:32:34 volumio kernel: hid-generic 0003:2622:0041.000F: hiddev0,hidraw0: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input3 May 15 09:32:34 volumio kernel: input: Audiolab Audiolab 2.0 as /devices/platform/soc/1c1c000.usb/usb4/4-1/4-1:1.4/0003:2622:0041.0010/input/input8 May 15 09:32:34 volumio kernel: hid-generic 0003:2622:0041.0010: input,hidraw1: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input4 May 15 09:32:34 volumio systemd-udevd[29716]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. May 15 09:32:34 volumio systemd-udevd[29718]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 09:32:34 volumio volumio[29628]: info: Loading plugin "outputs"... May 15 09:32:34 volumio volumio[29628]: info: Loading plugin "albumart"... May 15 09:32:34 volumio volumio[29628]: info: Plugin example_plugin is not enabled May 15 09:32:34 volumio volumio[29628]: info: Loading plugin "inputs"... May 15 09:32:34 volumio volumio[29628]: info: Loading plugin "updater_comm"... May 15 09:32:35 volumio volumio[29628]: info: Plugin mpdemulation is not enabled May 15 09:32:35 volumio volumio[29628]: info: Loading plugin "rest_api"... May 15 09:32:35 volumio volumio[29628]: info: Loading plugin "websocket"... May 15 09:32:35 volumio volumio[29628]: info: Loading plugin "80s80s"... May 15 09:32:35 volumio volumio[29628]: Forking 3 albumart workers May 15 09:32:36 volumio volumio[29628]: info: Applying required configuration parameters for plugin 80s80s May 15 09:32:36 volumio volumio[29628]: info: [1715758356036] [80s80s] API delay: 30 May 15 09:32:36 volumio volumio[29628]: info: Loading plugin "minidlna"... May 15 09:32:36 volumio volumio[29628]: info: Applying required configuration parameters for plugin minidlna May 15 09:32:36 volumio volumio[29628]: info: Loading plugin "motherearthradio"... May 15 09:32:36 volumio volumio-remote-updater[589]: [2024-05-15 09:32:36] [connect] Successful connection May 15 09:32:37 volumio volumio[29628]: info: Applying required configuration parameters for plugin motherearthradio May 15 09:32:37 volumio volumio[29628]: info: [1715758357136] [MotherEarth] API delay: 5 May 15 09:32:37 volumio volumio[29628]: info: Loading plugin "personal_radio"... May 15 09:32:37 volumio volumio[29628]: Starting albumart workers May 15 09:32:37 volumio volumio[29628]: Starting albumart workers May 15 09:32:37 volumio volumio[29628]: Starting albumart workers May 15 09:32:38 volumio kernel: usb 4-1: USB disconnect, device number 9 May 15 09:32:38 volumio volumio[29628]: info: ControllerPersonalRadio::constructor May 15 09:32:38 volumio volumio[29628]: info: Loading plugin "podcast"... May 15 09:32:38 volumio kernel: usb 4-1: new high-speed USB device number 10 using ehci-platform May 15 09:32:39 volumio kernel: usb 4-1: New USB device found, idVendor=2622, idProduct=0041, bcdDevice= 1.40 May 15 09:32:39 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 15 09:32:39 volumio kernel: usb 4-1: Product: Audiolab 2.0 May 15 09:32:39 volumio kernel: usb 4-1: Manufacturer: Audiolab May 15 09:32:39 volumio kernel: usb 4-1: SerialNumber: 0 May 15 09:32:39 volumio kernel: usb 4-1: 1:3 : unsupported format bits 0x100000000 May 15 09:32:39 volumio kernel: hid-generic 0003:2622:0041.0011: hiddev0,hidraw0: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input3 May 15 09:32:39 volumio kernel: input: Audiolab Audiolab 2.0 as /devices/platform/soc/1c1c000.usb/usb4/4-1/4-1:1.4/0003:2622:0041.0012/input/input9 May 15 09:32:39 volumio kernel: hid-generic 0003:2622:0041.0012: input,hidraw1: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input4 May 15 09:32:39 volumio volumio[29628]: info: ControllerPodcast::constructor May 15 09:32:39 volumio volumio[29628]: info: Loading plugin "radio_paradise"... May 15 09:32:39 volumio kernel: usb 4-1: USB disconnect, device number 10 May 15 09:32:40 volumio volumio[29628]: info: Applying required configuration parameters for plugin radio_paradise May 15 09:32:40 volumio volumio[29628]: info: [1715758360156] [RadioParadise] API delay: 5 May 15 09:32:40 volumio volumio[29628]: info: Loading plugin "backup_restore"... May 15 09:32:40 volumio kernel: usb 4-1: new high-speed USB device number 11 using ehci-platform May 15 09:32:40 volumio volumio[29628]: info: Applying required configuration parameters for plugin backup_restore May 15 09:32:40 volumio volumio[29628]: info: Loading plugin "gpio_control"... May 15 09:32:40 volumio kernel: usb 4-1: New USB device found, idVendor=2622, idProduct=0041, bcdDevice= 1.40 May 15 09:32:40 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 15 09:32:40 volumio kernel: usb 4-1: Product: Audiolab 2.0 May 15 09:32:40 volumio kernel: usb 4-1: Manufacturer: Audiolab May 15 09:32:40 volumio kernel: usb 4-1: SerialNumber: 0 May 15 09:32:40 volumio kernel: usb 4-1: 1:3 : unsupported format bits 0x100000000 May 15 09:32:40 volumio kernel: hid-generic 0003:2622:0041.0013: hiddev0,hidraw0: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input3 May 15 09:32:40 volumio kernel: input: Audiolab Audiolab 2.0 as /devices/platform/soc/1c1c000.usb/usb4/4-1/4-1:1.4/0003:2622:0041.0014/input/input10 May 15 09:32:40 volumio kernel: hid-generic 0003:2622:0041.0014: input,hidraw1: USB HID v1.10 Device [Audiolab Audiolab 2.0] on usb-1c1c000.usb-1/input4 May 15 09:32:41 volumio volumio[29628]: info: Applying required configuration parameters for plugin gpio_control May 15 09:32:41 volumio volumio[29628]: info: [GPIO_Control] Detected Xunlong Orange Pi One May 15 09:32:41 volumio volumio[29628]: info: [GPIO_Control] 40 GPIOs: false May 15 09:32:41 volumio volumio[29628]: info: [GPIO_Control] Initialized May 15 09:32:41 volumio volumio[29628]: info: Loading i18n strings for locale nl May 15 09:32:41 volumio volumio[29628]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 15 09:32:41 volumio volumio[29628]: Updating browse sources language May 15 09:32:41 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::initPlayerControls May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:42 volumio volumio[29628]: Express server listening on port 3000 May 15 09:32:42 volumio volumio[29628]: [Metrics] WebUI: 35s 157.16ms May 15 09:32:42 volumio volumio[29628]: info: CoreStateMachine::resetVolumioState May 15 09:32:42 volumio volumio[29628]: info: CoreStateMachine::getcurrentVolume May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::volumioRetrievevolume May 15 09:32:42 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:42 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:42 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:42 volumio volumio[29628]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds May 15 09:32:42 volumio sudo[29787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:32:42 volumio sudo[29787]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:42 volumio sudo[29787]: pam_unix(sudo:session): session closed for user root May 15 09:32:42 volumio sudo[29790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:32:42 volumio sudo[29790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:42 volumio volumio[29628]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 15 09:32:42 volumio sudo[29790]: pam_unix(sudo:session): session closed for user root May 15 09:32:42 volumio volumio[29628]: info: Completed loading Core Plugins May 15 09:32:42 volumio volumio[29628]: info: Preparing to generate the ALSA configuration file May 15 09:32:43 volumio volumio[29628]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 15 09:32:43 volumio volumio[29628]: wlan0 Interface doesn't support scanning. May 15 09:32:43 volumio volumio[29628]: info: Cannot use regular scanning, forcing with ap-force May 15 09:32:43 volumio sudo[29795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 15 09:32:43 volumio sudo[29795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:43 volumio sudo[29795]: pam_unix(sudo:session): session closed for user root May 15 09:32:43 volumio volumio[29628]: command failed: No such device (-19) May 15 09:32:43 volumio volumio[29628]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 15 09:32:43 volumio volumio[29628]: command failed: No such device (-19) May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6 May 15 09:32:43 volumio volumio[29628]: info: May 15 09:32:43 volumio volumio[29628]: ---------------------------- USB Audio Device Attached May 15 09:32:43 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 15 09:32:43 volumio volumio[29628]: error: API:pushError: TypeError: Cannot read property 'name' of null May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 7 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 May 15 09:32:43 volumio volumio[29628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 15 09:32:43 volumio volumio[29628]: info: Reloading queue from file May 15 09:32:44 volumio volumio[29628]: info: Asound.conf file unchanged, so no further update is needed May 15 09:32:44 volumio volumio[29628]: info: Output device has changed, restarting MPD May 15 09:32:44 volumio sudo[29809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:32:44 volumio sudo[29809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:44 volumio volumio[29628]: info: Output device has changed, restarting Shairport Sync May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:44 volumio sudo[29809]: pam_unix(sudo:session): session closed for user root May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:44 volumio sudo[29812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:32:44 volumio sudo[29812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:44 volumio systemd[1]: Stopping Music Player Daemon... May 15 09:32:44 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:44 volumio volumio[29628]: info: ___________ START PLUGINS ___________ May 15 09:32:44 volumio volumio[29628]: info: ControllerMpd::onStart: Initializing MPD May 15 09:32:44 volumio volumio[29628]: info: Creating MPD Configuration file May 15 09:32:44 volumio sudo[29818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:32:44 volumio sudo[29818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:44 volumio sudo[29818]: pam_unix(sudo:session): session closed for user root May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364411] CoreMusicLibrary::Adding element Media Servers May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio sudo[29821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:32:44 volumio sudo[29821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:44 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:44 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364713] CoreMusicLibrary::Adding element Last_100 May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364739] CoreMusicLibrary::Adding element Webradio May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 09:32:44 volumio volumio[29628]: info: Adding Calm Radio to Browse Sources May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364782] CoreMusicLibrary::Adding element Calm Radio May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:32:44 volumio volumio[29628]: info: Discovery: Getting this device information May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:44 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364839] CoreMusicLibrary::Adding element Jellyfin May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:44 volumio volumio[29628]: info: [jellyfin] Initialized plugin with device info: {"id":"a2317346-e94f-4d0b-982e-4bcd2c3fa530","host":"http://192.168.178.217","name":"volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"Bob Dylan","track":"Rainy Day Women # 12 & 35","albumart":"http://192.168.178.217/albumart?cacheid=524&web=Bob%20Dylan/Bob%20Dylan's%20Greatest%20Hits/extralarge&path=%2FNAS%2Fnas%2Fdsf%2FBob%20Dylan%20-%20Bob%20Dylan's%20Greatest%20Hits&metadata=false"}} May 15 09:32:44 volumio systemd[1]: mpd.service: Succeeded. May 15 09:32:44 volumio systemd[1]: Stopped Music Player Daemon. May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:44 volumio volumio[29628]: info: [1715758364869] CoreMusicLibrary::Adding element YouTube2 May 15 09:32:44 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:44 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:44 volumio systemd[1]: Starting Music Player Daemon... May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365012] CoreMusicLibrary::Adding element YouTube Music May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: info: GPIO-Buttons: Reading config and creating triggers... May 15 09:32:45 volumio volumio[29628]: info: [now-playing] ConfigUpdater: config is up to date. May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365100] CoreMusicLibrary::Adding element 80s80s Radio May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source 80s80s Radio May 15 09:32:45 volumio volumio[29628]: info: Loading i18n strings for locale nl May 15 09:32:45 volumio volumio[29628]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365212] CoreMusicLibrary::Adding element Mother Earth Radio May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source 80s80s Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Mother Earth Radio May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365405] CoreMusicLibrary::Adding element Personal Radio May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source 80s80s Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Mother Earth Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Personal Radio May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365444] CoreMusicLibrary::Adding element Podcast May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source 80s80s Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Mother Earth Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Personal Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Podcast May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 09:32:45 volumio volumio[29628]: info: [1715758365473] CoreMusicLibrary::Adding element Radio Paradise May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Calm Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Jellyfin May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube2 May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source YouTube Music May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source 80s80s Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Mother Earth Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Personal Radio May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Podcast May 15 09:32:45 volumio volumio[29628]: Cannot find translation for source Radio Paradise May 15 09:32:45 volumio volumio[29628]: info: [GPIO_Control] Reading config and creating GPIOs May 15 09:32:45 volumio volumio[29628]: info: [GPIO_Control] Will set GPIO 7 On when musicPlay May 15 09:32:45 volumio volumio[29628]: info: [GPIO_Control] Will set GPIO 3 Off when musicPause May 15 09:32:45 volumio volumio[29628]: info: Volumio Calling Home May 15 09:32:45 volumio volumio[29628]: info: GPIO-Buttons started May 15 09:32:45 volumio volumio[29628]: info: [GPIO_Control] GPIOs created May 15 09:32:45 volumio volumio[29628]: info: [now-playing] App is listening on port 4004. May 15 09:32:45 volumio volumio[29628]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 15 09:32:45 volumio volumio[29628]: info: CoreStateMachine::setRepeat null single undefined May 15 09:32:45 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:45 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:45 volumio volumio[29628]: info: CoreStateMachine::setRandom null May 15 09:32:45 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:45 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:45 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:46 volumio volumio[29628]: info: minidlna: Found miniDLNA version 1.2.1 May 15 09:32:46 volumio volumio[29628]: info: minidlna: Starting minidlna.service May 15 09:32:46 volumio volumio[29628]: info: MPD Permissions set May 15 09:32:46 volumio volumio[29628]: info: MPD Permissions set May 15 09:32:46 volumio sudo[29838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 15 09:32:46 volumio sudo[29838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:46 volumio volumio[29628]: info: May 15 09:32:46 volumio volumio[29628]: ---------------------------- USB Audio Device Detached May 15 09:32:46 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 15 09:32:46 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:46 volumio sudo[29838]: pam_unix(sudo:session): session closed for user root May 15 09:32:46 volumio sudo[29841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 15 09:32:46 volumio sudo[29841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:46 volumio sudo[29841]: pam_unix(sudo:session): session closed for user root May 15 09:32:46 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 15 09:32:46 volumio systemd-udevd[29715]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. May 15 09:32:47 volumio volumio[29628]: info: Volumio called home May 15 09:32:47 volumio volumio[29628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 May 15 09:32:47 volumio volumio[29628]: info: Upmpdcli Daemon Started May 15 09:32:47 volumio volumio[29628]: info: minidlna: systemctl start minidlna.service succeeded. May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:47 volumio volumio[29628]: info: Discovery: adding a2317346-e94f-4d0b-982e-4bcd2c3fa530 May 15 09:32:47 volumio volumio[29628]: info: Discovery: Found device volumio May 15 09:32:47 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:47 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:48 volumio volumio[29628]: info: Cannot mount NAS nas at system boot, trial number 3 ,retrying in 5 seconds May 15 09:32:48 volumio volumio[29628]: info: May 15 09:32:48 volumio volumio[29628]: ---------------------------- USB Audio Device Attached May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::Close All Modals sent May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:48 volumio volumio[29628]: info: Device 5 does not have any Mixer Control Available May 15 09:32:48 volumio mpd[29830]: May 15 09:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 09:32:48 volumio volumio[29628]: info: Updating Volume Controller Parameters: Device: 5 Name: Audiolab 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 09:32:48 volumio volumio[29628]: info: Disabling external Volume Control May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:48 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:48 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:48 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:48 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:48 volumio volumio[29628]: info: Preparing to generate the ALSA configuration file May 15 09:32:49 volumio volumio[29628]: (node:29628) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 15 09:32:49 volumio volumio[29628]: (Use `node --trace-deprecation ...` to show where the warning was created) May 15 09:32:49 volumio volumio[29628]: (node:29628) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 15 09:32:49 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:49 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:49 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 15 09:32:49 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:49 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:49 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:49 volumio sudo[29874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:49 volumio sudo[29874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:49 volumio sudo[29876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:49 volumio sudo[29876]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:32:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:32:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:32:49 volumio volumio[29628]: info: May 15 09:32:49 volumio volumio[29628]: ---------------------------- USB Audio Device Detached May 15 09:32:49 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 15 09:32:49 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:32:49 volumio sudo[29876]: pam_unix(sudo:session): session closed for user root May 15 09:32:49 volumio sudo[29880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:49 volumio sudo[29874]: pam_unix(sudo:session): session closed for user root May 15 09:32:49 volumio sudo[29880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:32:49 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 15 09:32:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:32:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:32:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:32:49 volumio sudo[29880]: pam_unix(sudo:session): session closed for user root May 15 09:32:50 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 11 May 15 09:32:50 volumio systemd-udevd[29801]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. May 15 09:32:50 volumio systemd-udevd[29718]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 09:32:50 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:50 volumio volumio[29628]: Error adding Membership: Error: addMembership EINVAL May 15 09:32:50 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:50 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 09:32:50 volumio volumio[29628]: info: May 15 09:32:50 volumio volumio[29628]: ---------------------------- USB Audio Device Attached May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::Close All Modals sent May 15 09:32:50 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:51 volumio volumio[29628]: info: Device 5 does not have any Mixer Control Available May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 09:32:51 volumio volumio[29628]: info: Updating Volume Controller Parameters: Device: 5 Name: Audiolab 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 09:32:51 volumio volumio[29628]: info: Disabling external Volume Control May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:51 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:51 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:51 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:51 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:51 volumio volumio[29628]: info: Asound.conf file written May 15 09:32:51 volumio sudo[29908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 15 09:32:51 volumio sudo[29908]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:51 volumio sudo[29908]: pam_unix(sudo:session): session closed for user root May 15 09:32:51 volumio volumio-remote-updater[589]: [2024-05-15 09:32:51] [connect] Successful connection May 15 09:32:51 volumio volumio[29628]: No state is present for card A20 May 15 09:32:51 volumio volumio[29628]: Found hardware: "USB-Audio" "USB Mixer" "USB2622:0041" "" "" May 15 09:32:51 volumio volumio[29628]: Hardware is initialized using a generic method May 15 09:32:51 volumio volumio[29628]: No state is present for card A20 May 15 09:32:51 volumio volumio[29628]: info: Output device has changed, restarting MPD May 15 09:32:51 volumio sudo[29913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:32:51 volumio sudo[29913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:51 volumio volumio[29628]: info: Output device has changed, restarting Shairport Sync May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:51 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:51 volumio sudo[29913]: pam_unix(sudo:session): session closed for user root May 15 09:32:51 volumio sudo[29916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:32:51 volumio sudo[29916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:51 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:51 volumio volumio[29628]: info: Preparing to generate the ALSA configuration file May 15 09:32:51 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 09:32:51 volumio systemd[1]: mpd.service: Succeeded. May 15 09:32:51 volumio systemd[1]: Stopped Music Player Daemon. May 15 09:32:51 volumio systemd[1]: Starting Music Player Daemon... May 15 09:32:51 volumio volumio[29628]: info: Output device has changed, restarting MPD May 15 09:32:52 volumio sudo[29923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:32:52 volumio sudo[29923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:52 volumio sudo[29923]: pam_unix(sudo:session): session closed for user root May 15 09:32:52 volumio volumio[29628]: info: Output device has changed, restarting Shairport Sync May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:52 volumio sudo[29926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:32:52 volumio sudo[29926]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 09:32:52 volumio systemd[1]: mpd.service: Succeeded. May 15 09:32:52 volumio systemd[1]: Stopped Music Player Daemon. May 15 09:32:52 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:52 volumio systemd[1]: Starting Music Player Daemon... May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 15 09:32:52 volumio sudo[29934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:32:52 volumio sudo[29934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:52 volumio sudo[29934]: pam_unix(sudo:session): session closed for user root May 15 09:32:52 volumio volumio[29628]: info: MPD Permissions set May 15 09:32:52 volumio volumio[29628]: info: MPD Permissions set May 15 09:32:52 volumio volumio-remote-updater[589]: [2024-05-15 09:32:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715758371 101 May 15 09:32:52 volumio sudo[29937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:32:52 volumio sudo[29937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:52 volumio volumio[29628]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 12 May 15 09:32:52 volumio sudo[29937]: pam_unix(sudo:session): session closed for user root May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:52 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: Asound.conf file unchanged, so no further update is needed May 15 09:32:53 volumio volumio[29628]: info: Output device has changed, restarting MPD May 15 09:32:53 volumio sudo[29958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 09:32:53 volumio sudo[29958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:53 volumio volumio[29628]: info: Output device has changed, restarting Shairport Sync May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:53 volumio sudo[29958]: pam_unix(sudo:session): session closed for user root May 15 09:32:53 volumio sudo[29961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 09:32:53 volumio sudo[29961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:53 volumio volumio[29628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 09:32:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 09:32:53 volumio systemd[1]: mpd.service: Succeeded. May 15 09:32:53 volumio systemd[1]: Stopped Music Player Daemon. May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 15 09:32:53 volumio volumio[29628]: info: CoreStateMachine::getcurrentVolume May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioRetrievevolume May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:53 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:53 volumio systemd[1]: Starting Music Player Daemon... May 15 09:32:53 volumio volumio[29628]: info: Cannot mount NAS nas at system boot, trial number 4 ,retrying in 5 seconds May 15 09:32:53 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 13 May 15 09:32:53 volumio volumio[29628]: info: MPD Permissions set May 15 09:32:53 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:53 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:53 volumio sudo[29968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:53 volumio sudo[29968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:53 volumio sudo[29971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:53 volumio sudo[29971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:32:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:32:53 volumio sudo[29968]: pam_unix(sudo:session): session closed for user root May 15 09:32:53 volumio sudo[29971]: pam_unix(sudo:session): session closed for user root May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 09:32:53 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:53 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetVisibleSources May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetQueue May 15 09:32:53 volumio volumio[29628]: info: CoreStateMachine::getQueue May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getQueue May 15 09:32:53 volumio volumio[29628]: info: Listing playlists May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 09:32:53 volumio volumio[29628]: info: Received Get System Info May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:32:53 volumio volumio[29628]: info: Discovery: Getting this device information May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:53 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 09:32:53 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 09:32:54 volumio volumio[29628]: info: Starting Shairport Sync May 15 09:32:54 volumio sudo[29983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 09:32:54 volumio sudo[29983]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 09:32:54 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 09:32:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 09:32:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 09:32:54 volumio sudo[29983]: pam_unix(sudo:session): session closed for user root May 15 09:32:54 volumio volumio[29628]: info: Shairport-Sync Started May 15 09:32:54 volumio sudo[29989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:32:54 volumio sudo[29989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:54 volumio sudo[29989]: pam_unix(sudo:session): session closed for user root May 15 09:32:54 volumio sudo[29992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:32:54 volumio sudo[29992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:54 volumio sudo[29992]: pam_unix(sudo:session): session closed for user root May 15 09:32:54 volumio volumio[29628]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 15 09:32:54 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 13 May 15 09:32:55 volumio sudo[29997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 09:32:55 volumio sudo[29997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:55 volumio sudo[29997]: pam_unix(sudo:session): session closed for user root May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 15 09:32:55 volumio volumio[29628]: info: CoreStateMachine::getcurrentVolume May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioRetrievevolume May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:55 volumio volumio[29628]: info: CoreStateMachine::pushState May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioPushState May 15 09:32:55 volumio sudo[30000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 09:32:55 volumio sudo[30000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 09:32:55 volumio sudo[30000]: pam_unix(sudo:session): session closed for user root May 15 09:32:55 volumio volumio[29628]: verbose: New Socket.io Connection to 192.168.178.217 from 192.168.178.111 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 14 May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetVisibleSources May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetQueue May 15 09:32:55 volumio volumio[29628]: info: CoreStateMachine::getQueue May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getQueue May 15 09:32:55 volumio volumio[29628]: info: Listing playlists May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 09:32:55 volumio volumio[29628]: info: Received Get System Info May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:32:55 volumio volumio[29628]: info: Discovery: Getting this device information May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:55 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 09:32:55 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 09:32:56 volumio mpd[29966]: May 15 09:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 09:32:57 volumio volumio[29628]: info: Received Get System Info May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:32:57 volumio volumio[29628]: info: Discovery: Getting this device information May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:57 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:32:57 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 09:32:58 volumio volumio[29628]: info: Received Get System Info May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 09:32:58 volumio volumio[29628]: info: Discovery: Getting this device information May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::volumioGetState May 15 09:32:58 volumio volumio[29628]: info: CorePlayQueue::getTrack 0 May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 09:32:58 volumio volumio[29628]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 15 09:32:58 volumio volumio[29628]: info: Preload queue cleared May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 09:32:58 volumio volumio[29628]: info: CoreStateMachine::ClearQueue May 15 09:32:58 volumio volumio[29628]: info: CoreStateMachine::stop May 15 09:32:58 volumio volumio[29628]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 09:32:58 volumio volumio[29628]: info: CorePlayQueue::clearPlayQueue May 15 09:32:58 volumio volumio[29628]: info: CorePlayQueue::saveQueue May 15 09:32:58 volumio volumio[29628]: info: CoreCommandRouter::volumioPushQueue May 15 09:32:58 volumio volumio[29628]: info: CoreStateMachine::addQueueItems May 15 09:32:58 volumio volumio[29628]: info: CorePlayQueue::addQueueItems May 15 09:32:58 volumio volumio[29628]: info: Preload queue cleared May 15 09:32:58 volumio volumio[29628]: info: Adding Item to queue: music-library/NAS/nas/dsf/Bob Dylan - Bob Dylan's Greatest Hits/01 - Bob Dylan - Rainy Day Women # 12 & 35.dsf May 15 09:32:58 volumio volumio[29628]: info: Exploding uri music-library/NAS/nas/dsf/Bob Dylan - Bob Dylan's Greatest Hits/01 - Bob Dylan - Rainy Day Women # 12 & 35.dsf in service mpd May 15 09:32:58 volumio volumio[29628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 09:32:58 volumio volumio[29628]: TypeError: Cannot read property 'then' of undefined May 15 09:32:58 volumio volumio[29628]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) May 15 09:32:58 volumio volumio[29628]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) May 15 09:32:58 volumio volumio[29628]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) May 15 09:32:58 volumio volumio[29628]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 15 09:32:58 volumio volumio[29628]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 15 09:32:58 volumio volumio[29628]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 15 09:32:58 volumio volumio[29628]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 15 09:32:58 volumio volumio[29628]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) May 15 09:32:58 volumio volumio[29628]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) May 15 09:32:58 volumio volumio[29628]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) May 15 09:32:58 volumio volumio[29628]: at Socket.emit (events.js:400:28) May 15 09:32:58 volumio volumio[29628]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 15 09:32:58 volumio volumio[29628]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 15 09:32:58 volumio volumio[29628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 09:32:59 volumio sudo[30014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 09:31 May 15 09:33:00 volumio sudo[30014]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 24 Feb 2024 07:51:12 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="orangepione" VOLUMIO_DEVICENAME="Orange Pi One" VOLUMIO_HASH="6bb0b93ee763464cdf8f7150f8ec39f6"