-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2023-08-05 18:56:22 CEST. -- Aug 05 18:55:00 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:00 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:01 volumio mpd[14833]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:01 volumio mpd[14833]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:02 volumio ntpd[642]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 05 18:55:02 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:02 volumio sudo[14821]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:02 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:02 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:02 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:02 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:02 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "outputs"... Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "albumart"... Aug 05 18:55:03 volumio volumio[14754]: info: Plugin example_plugin is not enabled Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "inputs"... Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "updater_comm"... Aug 05 18:55:03 volumio volumio[14754]: info: Plugin mpdemulation is not enabled Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "rest_api"... Aug 05 18:55:03 volumio volumio[14754]: info: Loading plugin "websocket"... Aug 05 18:55:03 volumio volumio[14754]: info: Plugin spop is not enabled Aug 05 18:55:03 volumio volumio[14754]: info: Loading i18n strings for locale pl Aug 05 18:55:03 volumio volumio[14754]: Updating browse sources language Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::initPlayerControls Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: Forking 3 albumart workers Aug 05 18:55:03 volumio volumio[14754]: Express server listening on port 3000 Aug 05 18:55:03 volumio volumio[14754]: [Metrics] WebUI: 9s 490.93ms Aug 05 18:55:03 volumio volumio[14754]: info: CoreStateMachine::resetVolumioState Aug 05 18:55:03 volumio volumio[14754]: info: CoreStateMachine::getcurrentVolume Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:03 volumio volumio[14754]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:03 volumio volumio[14754]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:55:03 volumio volumio[14754]: info: MPD Permissions set Aug 05 18:55:03 volumio volumio[14754]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:03 volumio volumio[14754]: info: CoreStateMachine::pushState Aug 05 18:55:03 volumio volumio[14754]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:03 volumio volumio[14754]: info: CoreStateMachine::updateTrackBlock Aug 05 18:55:03 volumio volumio[14754]: info: CorePlayQueue::getTrackBlock Aug 05 18:55:03 volumio volumio[14754]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:03 volumio volumio[14754]: info: MPD running with PID14833 Aug 05 18:55:03 volumio volumio[14754]: ,establishing connection Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: Reloading queue from file Aug 05 18:55:04 volumio volumio[14754]: error: updateQueue error: null Aug 05 18:55:04 volumio volumio[14754]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:55:04 volumio volumio[14754]: info: CoreStateMachine::pushState Aug 05 18:55:04 volumio volumio[14754]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:04 volumio volumio[14754]: info: CoreStateMachine::setRandom null Aug 05 18:55:04 volumio volumio[14754]: info: CoreStateMachine::pushState Aug 05 18:55:04 volumio volumio[14754]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:04 volumio volumio[14754]: info: Setting Device type: Raspberry PI Aug 05 18:55:04 volumio volumio[14754]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:04 volumio volumio[14754]: error: updateQueue error: null Aug 05 18:55:04 volumio volumio[14754]: info: Completed loading Core Plugins Aug 05 18:55:04 volumio volumio[14754]: info: Preparing to generate the ALSA configuration file Aug 05 18:55:04 volumio volumio[14754]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:04 volumio volumio[14754]: info: CoreStateMachine::pushState Aug 05 18:55:04 volumio volumio[14754]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:04 volumio volumio[14754]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:55:04 volumio volumio[14754]: info: Output device has changed, restarting MPD Aug 05 18:55:04 volumio volumio[14754]: info: Output device has changed, restarting Shairport Sync Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:04 volumio volumio[14754]: info: ___________ START PLUGINS ___________ Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:04 volumio volumio[14754]: info: [1691254504420] CoreMusicLibrary::Adding element Media Servers Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:04 volumio volumio[14754]: Starting albumart workers Aug 05 18:55:04 volumio volumio[14754]: Starting albumart workers Aug 05 18:55:04 volumio volumio[14754]: Starting albumart workers Aug 05 18:55:04 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:04 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:04 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:04 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:04 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:04 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:04 volumio sudo[14917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:04 volumio sudo[14917]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:04 volumio sudo[14914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:04 volumio sudo[14914]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:04 volumio sudo[14914]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:04 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:04 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:04 volumio volumio[14754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:04 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:04 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:04 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:05 volumio volumio[14754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:05 volumio volumio[14754]: info: [1691254505024] CoreMusicLibrary::Adding element Last_100 Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:05 volumio volumio[14754]: info: [1691254505030] CoreMusicLibrary::Adding element Webradio Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:05 volumio volumio[14754]: info: [youtube2-auth] Starting auth flow... Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:05 volumio volumio[14754]: info: [1691254505212] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:55:05 volumio volumio[14754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:05 volumio volumio[14754]: Cannot find translation for source YouTube2 Aug 05 18:55:05 volumio volumio[14754]: info: Volumio Calling Home Aug 05 18:55:05 volumio volumio[14754]: info: Completed starting Core Plugins Aug 05 18:55:05 volumio volumio[14754]: info: ------------------------------------------- Aug 05 18:55:05 volumio volumio[14754]: info: ----- MyVolumio plugins startup ---- Aug 05 18:55:05 volumio volumio[14754]: info: ------------------------------------------- Aug 05 18:55:05 volumio volumio[14754]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:55:05 volumio volumio[14754]: Unhandled rejection Error: No sockets available, cannot start. Aug 05 18:55:05 volumio volumio[14754]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 05 18:55:05 volumio volumio[14754]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 05 18:55:05 volumio volumio[14754]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 05 18:55:05 volumio volumio[14754]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 05 18:55:05 volumio volumio[14754]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 05 18:55:05 volumio volumio[14754]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 05 18:55:05 volumio volumio[14754]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 05 18:55:05 volumio volumio[14754]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 05 18:55:05 volumio volumio[14754]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 05 18:55:05 volumio volumio[14754]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 05 18:55:05 volumio volumio[14754]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 05 18:55:05 volumio volumio[14754]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 05 18:55:05 volumio volumio[14754]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 05 18:55:05 volumio volumio[14754]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 05 18:55:05 volumio volumio[14754]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 05 18:55:05 volumio volumio[14754]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 05 18:55:05 volumio volumio[14754]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:05 volumio volumio[14754]: ReferenceError: deferred is not defined Aug 05 18:55:05 volumio volumio[14754]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:55:05 volumio volumio[14754]: at ClientRequest.emit (events.js:315:20) Aug 05 18:55:05 volumio volumio[14754]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:55:05 volumio volumio[14754]: at TLSSocket.emit (events.js:315:20) Aug 05 18:55:05 volumio volumio[14754]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:55:05 volumio volumio[14754]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:55:05 volumio volumio[14754]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:55:05 volumio volumio[14754]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:05 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:05 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:06 volumio mpd[14945]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:07 volumio sudo[14981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:54 Aug 05 18:55:07 volumio sudo[14981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:07 volumio sudo[14981]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 05 18:55:07 volumio sudo[14917]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 05 18:55:07 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:07 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 05 18:55:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 54. Aug 05 18:55:07 volumio systemd[1]: Stopped Volumio Backend Module. Aug 05 18:55:07 volumio systemd[1]: Started Volumio Backend Module. Aug 05 18:55:07 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:07 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:08 volumio ntpd[642]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 05 18:55:08 volumio ntpd[642]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 05 18:55:09 volumio mpd[14945]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:09 volumio mpd[14945]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:09 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:09 volumio volumio[14994]: info: ----- Volumio3 ---- Aug 05 18:55:09 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:09 volumio volumio[14994]: info: ----- System startup ---- Aug 05 18:55:09 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:09 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:09 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:09 volumio volumio-remote-updater[467]: [2023-08-05 18:55:09] [connect] Successful connection Aug 05 18:55:09 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:09 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:09 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:09 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:10 volumio volumio[14994]: info: MYVOLUMIO Environment detected Aug 05 18:55:10 volumio volumio[14994]: info: Plugin folders cleanup Aug 05 18:55:10 volumio volumio[14994]: info: Scanning into folder /volumio/app/plugins/ Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category audio_interface Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category miscellanea Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category music_service Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category plugins.json Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category system_controller Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category user_interface Aug 05 18:55:10 volumio volumio[14994]: info: Scanning into folder /data/plugins/ Aug 05 18:55:10 volumio volumio[14994]: info: Scanning category music_service Aug 05 18:55:10 volumio volumio[14994]: info: Plugin folders cleanup completed Aug 05 18:55:10 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:10 volumio volumio[14994]: info: ----- Core plugins startup ---- Aug 05 18:55:10 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:10 volumio volumio[14994]: info: Loading plugins from folder /volumio/app/plugins/ Aug 05 18:55:10 volumio volumio[14994]: info: Adding plugin upnp to MyMusic Plugins Aug 05 18:55:10 volumio volumio[14994]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 05 18:55:10 volumio volumio[14994]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 05 18:55:10 volumio volumio[14994]: info: Loading plugins from folder /data/plugins/ Aug 05 18:55:10 volumio volumio[14994]: info: Loading plugin "system"... Aug 05 18:55:10 volumio volumio[14994]: info: Loading plugin "appearance"... Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:10 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:10 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:10 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:10 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:10 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "network"... Aug 05 18:55:11 volumio volumio[14994]: info: Refreshing Cached IP Addresses Aug 05 18:55:11 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "services"... Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "alsa_controller"... Aug 05 18:55:11 volumio volumio[14994]: warn: Unable to locate the audio output device Mini-i Series. Please configure a valid output device. Aug 05 18:55:11 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "wizard"... Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "volumio_command_line_client"... Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "upnp"... Aug 05 18:55:11 volumio volumio[14994]: info: [1691254511749] Starting Upmpd Daemon Aug 05 18:55:11 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "my_music"... Aug 05 18:55:11 volumio volumio[14994]: info: Loading plugin "mpd"... Aug 05 18:55:12 volumio volumio[14994]: info: Creating MPD Configuration file Aug 05 18:55:12 volumio volumio[14994]: info: Loading plugin "upnp_browser"... Aug 05 18:55:12 volumio sudo[15054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 05 18:55:12 volumio sudo[15054]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio sudo[15064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:12 volumio sudo[15054]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:12 volumio sudo[15058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 05 18:55:12 volumio sudo[15056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 05 18:55:12 volumio sudo[15056]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio sudo[15064]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio sudo[15058]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio sudo[15056]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:12 volumio sudo[15062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:12 volumio sudo[15062]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:12 volumio sudo[15062]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:12 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:12 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:12 volumio volumio[14994]: info: Loading plugin "networkfs"... Aug 05 18:55:12 volumio volumio[14994]: info: Starting Udev Watcher for removable devices Aug 05 18:55:12 volumio sudo[15075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Bartek,password=Hasl0_dla_B4rtka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Multimedia /mnt/NAS/NAS_Multimedia Aug 05 18:55:12 volumio sudo[15075]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:12 volumio sudo[15075]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:12 volumio kernel: CIFS: Attempting to mount //192.168.2.98/Multimedia Aug 05 18:55:12 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 05 18:55:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 05 18:55:13 volumio volumio[14994]: info: Ignoring mount for partition: boot Aug 05 18:55:13 volumio volumio[14994]: info: Ignoring mount for partition: volumio Aug 05 18:55:13 volumio volumio[14994]: info: Ignoring mount for partition: volumio_data Aug 05 18:55:13 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "alarm-clock"... Aug 05 18:55:13 volumio sudo[15058]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "airplay_emulation"... Aug 05 18:55:13 volumio volumio[14994]: info: Starting Shairport Sync Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "last_100"... Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "webradio"... Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "i2s_dacs"... Aug 05 18:55:13 volumio volumio[14994]: info: I2S DAC not set, start Auto-detection Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "volumiodiscovery"... Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** For more information see Aug 05 18:55:13 volumio node[14994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:13 volumio volumio[14994]: *** WARNING *** For more information see Aug 05 18:55:13 volumio node[14994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:13 volumio node[14994]: *** WARNING *** For more information see Aug 05 18:55:13 volumio node[14994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:13 volumio node[14994]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:13 volumio node[14994]: *** WARNING *** For more information see Aug 05 18:55:13 volumio volumio[14994]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 05 18:55:13 volumio volumio[14994]: info: Discovery: Started advertising with name: Volumio Aug 05 18:55:13 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:13 volumio volumio[14994]: info: Loading plugin "youtube2"... Aug 05 18:55:13 volumio mpd[15072]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:15 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:15 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:15 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:15 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:15 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:16 volumio mpd[15072]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:16 volumio mpd[15072]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:17 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:17 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:17 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:17 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:17 volumio sudo[15064]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:17 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:17 volumio ntpd[642]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 05 18:55:17 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:18 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "outputs"... Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "albumart"... Aug 05 18:55:18 volumio volumio[14994]: info: Plugin example_plugin is not enabled Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "inputs"... Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "updater_comm"... Aug 05 18:55:18 volumio volumio[14994]: info: Plugin mpdemulation is not enabled Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "rest_api"... Aug 05 18:55:18 volumio volumio[14994]: info: Loading plugin "websocket"... Aug 05 18:55:18 volumio volumio[14994]: info: Plugin spop is not enabled Aug 05 18:55:18 volumio volumio[14994]: info: Loading i18n strings for locale pl Aug 05 18:55:18 volumio volumio[14994]: Updating browse sources language Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::initPlayerControls Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: Express server listening on port 3000 Aug 05 18:55:18 volumio volumio[14994]: [Metrics] WebUI: 9s 512.91ms Aug 05 18:55:18 volumio volumio[14994]: Forking 3 albumart workers Aug 05 18:55:18 volumio volumio[14994]: info: CoreStateMachine::resetVolumioState Aug 05 18:55:18 volumio volumio[14994]: info: CoreStateMachine::getcurrentVolume Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:55:18 volumio volumio[14994]: info: MPD Permissions set Aug 05 18:55:18 volumio volumio[14994]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:18 volumio volumio[14994]: info: MPD running with PID15072 Aug 05 18:55:18 volumio volumio[14994]: ,establishing connection Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:18 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: Reloading queue from file Aug 05 18:55:19 volumio volumio[14994]: error: updateQueue error: null Aug 05 18:55:19 volumio volumio[14994]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::pushState Aug 05 18:55:19 volumio volumio[14994]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::updateTrackBlock Aug 05 18:55:19 volumio volumio[14994]: info: CorePlayQueue::getTrackBlock Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:19 volumio volumio[14994]: info: Setting Device type: Raspberry PI Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::pushState Aug 05 18:55:19 volumio volumio[14994]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::setRandom null Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::pushState Aug 05 18:55:19 volumio volumio[14994]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:19 volumio volumio[14994]: error: updateQueue error: null Aug 05 18:55:19 volumio volumio[14994]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:19 volumio volumio[14994]: info: Completed loading Core Plugins Aug 05 18:55:19 volumio volumio[14994]: info: Preparing to generate the ALSA configuration file Aug 05 18:55:19 volumio volumio[14994]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:19 volumio volumio[14994]: info: CoreStateMachine::pushState Aug 05 18:55:19 volumio volumio[14994]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:19 volumio volumio[14994]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:55:19 volumio volumio[14994]: info: Output device has changed, restarting MPD Aug 05 18:55:19 volumio volumio[14994]: info: Output device has changed, restarting Shairport Sync Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:19 volumio volumio[14994]: info: ___________ START PLUGINS ___________ Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:19 volumio volumio[14994]: info: [1691254519389] CoreMusicLibrary::Adding element Media Servers Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:19 volumio volumio[14994]: Starting albumart workers Aug 05 18:55:19 volumio volumio[14994]: Starting albumart workers Aug 05 18:55:19 volumio volumio[14994]: Starting albumart workers Aug 05 18:55:19 volumio sudo[15163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:19 volumio sudo[15163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:19 volumio sudo[15165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:19 volumio sudo[15165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:19 volumio sudo[15163]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:19 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:19 volumio volumio[14994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:19 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:19 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:19 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:20 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:20 volumio volumio[14994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:20 volumio volumio[14994]: info: [1691254520098] CoreMusicLibrary::Adding element Last_100 Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:20 volumio volumio[14994]: info: [1691254520103] CoreMusicLibrary::Adding element Webradio Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:20 volumio volumio[14994]: info: [youtube2-auth] Starting auth flow... Aug 05 18:55:20 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:20 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:20 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:20 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:20 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:20 volumio volumio[14994]: info: [1691254520497] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:55:20 volumio volumio[14994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:20 volumio volumio[14994]: Cannot find translation for source YouTube2 Aug 05 18:55:20 volumio volumio[14994]: info: Volumio Calling Home Aug 05 18:55:20 volumio volumio[14994]: info: Completed starting Core Plugins Aug 05 18:55:20 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:20 volumio volumio[14994]: info: ----- MyVolumio plugins startup ---- Aug 05 18:55:20 volumio volumio[14994]: info: ------------------------------------------- Aug 05 18:55:20 volumio volumio[14994]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:55:20 volumio volumio[14994]: Unhandled rejection Error: No sockets available, cannot start. Aug 05 18:55:20 volumio volumio[14994]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 05 18:55:20 volumio volumio[14994]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 05 18:55:20 volumio volumio[14994]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 05 18:55:20 volumio volumio[14994]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 05 18:55:20 volumio volumio[14994]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 05 18:55:20 volumio volumio[14994]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 05 18:55:20 volumio volumio[14994]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 05 18:55:20 volumio volumio[14994]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 05 18:55:20 volumio volumio[14994]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 05 18:55:20 volumio volumio[14994]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 05 18:55:20 volumio volumio[14994]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 05 18:55:20 volumio volumio[14994]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 05 18:55:20 volumio volumio[14994]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 05 18:55:20 volumio volumio[14994]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 05 18:55:20 volumio volumio[14994]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 05 18:55:20 volumio volumio[14994]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 05 18:55:20 volumio volumio[14994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:20 volumio volumio[14994]: ReferenceError: deferred is not defined Aug 05 18:55:20 volumio volumio[14994]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:55:20 volumio volumio[14994]: at ClientRequest.emit (events.js:315:20) Aug 05 18:55:20 volumio volumio[14994]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:55:20 volumio volumio[14994]: at TLSSocket.emit (events.js:315:20) Aug 05 18:55:20 volumio volumio[14994]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:55:20 volumio volumio[14994]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:55:20 volumio volumio[14994]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:55:20 volumio volumio[14994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:21 volumio mpd[15190]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:22 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:22 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:22 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:22 volumio sudo[15236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:54 Aug 05 18:55:22 volumio sudo[15236]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:22 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:22 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:22 volumio sudo[15236]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 05 18:55:22 volumio sudo[15165]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 05 18:55:22 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:22 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 05 18:55:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 55. Aug 05 18:55:22 volumio systemd[1]: Stopped Volumio Backend Module. Aug 05 18:55:22 volumio systemd[1]: Started Volumio Backend Module. Aug 05 18:55:22 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:22 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:23 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:24 volumio mpd[15190]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:24 volumio mpd[15190]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:24 volumio volumio-remote-updater[467]: [2023-08-05 18:55:24] [connect] Successful connection Aug 05 18:55:24 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:24 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:24 volumio volumio[15263]: info: ----- Volumio3 ---- Aug 05 18:55:24 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:24 volumio volumio[15263]: info: ----- System startup ---- Aug 05 18:55:24 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:25 volumio volumio[15263]: info: MYVOLUMIO Environment detected Aug 05 18:55:25 volumio volumio[15263]: info: Plugin folders cleanup Aug 05 18:55:25 volumio volumio[15263]: info: Scanning into folder /volumio/app/plugins/ Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category audio_interface Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category miscellanea Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category music_service Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category plugins.json Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category system_controller Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category user_interface Aug 05 18:55:25 volumio volumio[15263]: info: Scanning into folder /data/plugins/ Aug 05 18:55:25 volumio volumio[15263]: info: Scanning category music_service Aug 05 18:55:25 volumio volumio[15263]: info: Plugin folders cleanup completed Aug 05 18:55:25 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:25 volumio volumio[15263]: info: ----- Core plugins startup ---- Aug 05 18:55:25 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:25 volumio volumio[15263]: info: Loading plugins from folder /volumio/app/plugins/ Aug 05 18:55:25 volumio volumio[15263]: info: Adding plugin upnp to MyMusic Plugins Aug 05 18:55:25 volumio volumio[15263]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 05 18:55:25 volumio volumio[15263]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 05 18:55:25 volumio volumio[15263]: info: Loading plugins from folder /data/plugins/ Aug 05 18:55:25 volumio volumio[15263]: info: Loading plugin "system"... Aug 05 18:55:25 volumio volumio[15263]: info: Loading plugin "appearance"... Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "network"... Aug 05 18:55:26 volumio volumio[15263]: info: Refreshing Cached IP Addresses Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "services"... Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "alsa_controller"... Aug 05 18:55:26 volumio volumio[15263]: warn: Unable to locate the audio output device Mini-i Series. Please configure a valid output device. Aug 05 18:55:26 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "wizard"... Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "volumio_command_line_client"... Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "upnp"... Aug 05 18:55:26 volumio volumio[15263]: info: [1691254526962] Starting Upmpd Daemon Aug 05 18:55:26 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "my_music"... Aug 05 18:55:26 volumio volumio[15263]: info: Loading plugin "mpd"... Aug 05 18:55:27 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:27 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:27 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:27 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:27 volumio sudo[15293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 05 18:55:27 volumio sudo[15293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:27 volumio sudo[15291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 05 18:55:27 volumio sudo[15291]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:27 volumio sudo[15289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 05 18:55:27 volumio sudo[15289]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:27 volumio sudo[15291]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:27 volumio sudo[15289]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:27 volumio volumio[15263]: info: Creating MPD Configuration file Aug 05 18:55:27 volumio volumio[15263]: info: Loading plugin "upnp_browser"... Aug 05 18:55:27 volumio sudo[15320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:27 volumio sudo[15320]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:27 volumio sudo[15320]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:27 volumio sudo[15322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:27 volumio sudo[15322]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:27 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:27 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:27 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:27 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:27 volumio volumio[15263]: info: Loading plugin "networkfs"... Aug 05 18:55:27 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:27 volumio sudo[15293]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:28 volumio volumio[15263]: info: Starting Udev Watcher for removable devices Aug 05 18:55:28 volumio sudo[15330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Bartek,password=Hasl0_dla_B4rtka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Multimedia /mnt/NAS/NAS_Multimedia Aug 05 18:55:28 volumio sudo[15330]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:28 volumio sudo[15330]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:28 volumio kernel: CIFS: Attempting to mount //192.168.2.98/Multimedia Aug 05 18:55:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 05 18:55:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 05 18:55:28 volumio volumio[15263]: info: Ignoring mount for partition: boot Aug 05 18:55:28 volumio volumio[15263]: info: Ignoring mount for partition: volumio Aug 05 18:55:28 volumio volumio[15263]: info: Ignoring mount for partition: volumio_data Aug 05 18:55:28 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "alarm-clock"... Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "airplay_emulation"... Aug 05 18:55:28 volumio volumio[15263]: info: Starting Shairport Sync Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "last_100"... Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "webradio"... Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "i2s_dacs"... Aug 05 18:55:28 volumio volumio[15263]: info: I2S DAC not set, start Auto-detection Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "volumiodiscovery"... Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:28 volumio node[15263]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** For more information see Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:28 volumio volumio[15263]: *** WARNING *** For more information see Aug 05 18:55:28 volumio node[15263]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:28 volumio node[15263]: *** WARNING *** For more information see Aug 05 18:55:28 volumio node[15263]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:28 volumio node[15263]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:28 volumio node[15263]: *** WARNING *** For more information see Aug 05 18:55:28 volumio volumio[15263]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 05 18:55:28 volumio volumio[15263]: info: Discovery: Started advertising with name: Volumio Aug 05 18:55:28 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:28 volumio volumio[15263]: info: Loading plugin "youtube2"... Aug 05 18:55:29 volumio mpd[15327]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:29 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:29 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:29 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:29 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:29 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:29 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:29 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:29 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:29 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:30 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:30 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:31 volumio mpd[15327]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:31 volumio mpd[15327]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:31 volumio systemd[1]: Starting Cleanup of Temporary Directories... Aug 05 18:55:31 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Aug 05 18:55:31 volumio systemd[1]: Started Cleanup of Temporary Directories. Aug 05 18:55:32 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:32 volumio sudo[15322]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:32 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:32 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:32 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:32 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:32 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "outputs"... Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "albumart"... Aug 05 18:55:33 volumio volumio[15263]: info: Plugin example_plugin is not enabled Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "inputs"... Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "updater_comm"... Aug 05 18:55:33 volumio volumio[15263]: info: Plugin mpdemulation is not enabled Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "rest_api"... Aug 05 18:55:33 volumio volumio[15263]: info: Loading plugin "websocket"... Aug 05 18:55:33 volumio volumio[15263]: info: Plugin spop is not enabled Aug 05 18:55:33 volumio volumio[15263]: info: Loading i18n strings for locale pl Aug 05 18:55:33 volumio volumio[15263]: Updating browse sources language Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::initPlayerControls Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: Forking 3 albumart workers Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: Express server listening on port 3000 Aug 05 18:55:33 volumio volumio[15263]: [Metrics] WebUI: 9s 479.92ms Aug 05 18:55:33 volumio volumio[15263]: info: CoreStateMachine::resetVolumioState Aug 05 18:55:33 volumio volumio[15263]: info: CoreStateMachine::getcurrentVolume Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:33 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:33 volumio volumio[15263]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:34 volumio volumio[15263]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:55:34 volumio volumio[15263]: info: MPD Permissions set Aug 05 18:55:34 volumio volumio[15263]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::pushState Aug 05 18:55:34 volumio volumio[15263]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::updateTrackBlock Aug 05 18:55:34 volumio volumio[15263]: info: CorePlayQueue::getTrackBlock Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:34 volumio volumio[15263]: info: MPD running with PID15327 Aug 05 18:55:34 volumio volumio[15263]: ,establishing connection Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: Reloading queue from file Aug 05 18:55:34 volumio volumio[15263]: error: updateQueue error: null Aug 05 18:55:34 volumio volumio[15263]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::pushState Aug 05 18:55:34 volumio volumio[15263]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::setRandom null Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::pushState Aug 05 18:55:34 volumio volumio[15263]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:34 volumio volumio[15263]: info: Setting Device type: Raspberry PI Aug 05 18:55:34 volumio volumio[15263]: error: updateQueue error: null Aug 05 18:55:34 volumio volumio[15263]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:34 volumio volumio[15263]: info: CoreStateMachine::pushState Aug 05 18:55:34 volumio volumio[15263]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:34 volumio volumio[15263]: info: Completed loading Core Plugins Aug 05 18:55:34 volumio volumio[15263]: info: Preparing to generate the ALSA configuration file Aug 05 18:55:34 volumio volumio[15263]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:55:34 volumio volumio[15263]: info: Output device has changed, restarting MPD Aug 05 18:55:34 volumio volumio[15263]: info: Output device has changed, restarting Shairport Sync Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:34 volumio volumio[15263]: info: ___________ START PLUGINS ___________ Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:34 volumio volumio[15263]: info: [1691254534665] CoreMusicLibrary::Adding element Media Servers Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:34 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:34 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:34 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:34 volumio volumio[15263]: Starting albumart workers Aug 05 18:55:34 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:34 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:34 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:34 volumio sudo[15420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:34 volumio sudo[15420]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:34 volumio volumio[15263]: Starting albumart workers Aug 05 18:55:34 volumio sudo[15422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:34 volumio sudo[15422]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:34 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:34 volumio sudo[15420]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:34 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:34 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:34 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:34 volumio volumio[15263]: Starting albumart workers Aug 05 18:55:34 volumio volumio[15263]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:34 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:35 volumio volumio[15263]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:35 volumio volumio[15263]: info: [1691254535040] CoreMusicLibrary::Adding element Last_100 Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:35 volumio volumio[15263]: info: [1691254535044] CoreMusicLibrary::Adding element Webradio Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:35 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:35 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:35 volumio volumio[15263]: info: [youtube2-auth] Starting auth flow... Aug 05 18:55:35 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:35 volumio volumio[15263]: info: [1691254535208] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:55:35 volumio volumio[15263]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:35 volumio volumio[15263]: Cannot find translation for source YouTube2 Aug 05 18:55:35 volumio volumio[15263]: info: Volumio Calling Home Aug 05 18:55:35 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:35 volumio volumio[15263]: info: Completed starting Core Plugins Aug 05 18:55:35 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:35 volumio volumio[15263]: info: ----- MyVolumio plugins startup ---- Aug 05 18:55:35 volumio volumio[15263]: info: ------------------------------------------- Aug 05 18:55:35 volumio volumio[15263]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:55:35 volumio volumio[15263]: Unhandled rejection Error: No sockets available, cannot start. Aug 05 18:55:35 volumio volumio[15263]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 05 18:55:35 volumio volumio[15263]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 05 18:55:35 volumio volumio[15263]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 05 18:55:35 volumio volumio[15263]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 05 18:55:35 volumio volumio[15263]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 05 18:55:35 volumio volumio[15263]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 05 18:55:35 volumio volumio[15263]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 05 18:55:35 volumio volumio[15263]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 05 18:55:35 volumio volumio[15263]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 05 18:55:35 volumio volumio[15263]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 05 18:55:35 volumio volumio[15263]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 05 18:55:35 volumio volumio[15263]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 05 18:55:35 volumio volumio[15263]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 05 18:55:35 volumio volumio[15263]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 05 18:55:35 volumio volumio[15263]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 05 18:55:35 volumio volumio[15263]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 05 18:55:35 volumio volumio[15263]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:35 volumio volumio[15263]: ReferenceError: deferred is not defined Aug 05 18:55:35 volumio volumio[15263]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:55:35 volumio volumio[15263]: at ClientRequest.emit (events.js:315:20) Aug 05 18:55:35 volumio volumio[15263]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:55:35 volumio volumio[15263]: at TLSSocket.emit (events.js:315:20) Aug 05 18:55:35 volumio volumio[15263]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:55:35 volumio volumio[15263]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:55:35 volumio volumio[15263]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:55:35 volumio volumio[15263]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:36 volumio mpd[15451]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:37 volumio sudo[15486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:54 Aug 05 18:55:37 volumio sudo[15486]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:37 volumio sudo[15486]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 05 18:55:37 volumio sudo[15422]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 05 18:55:37 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 05 18:55:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 56. Aug 05 18:55:37 volumio systemd[1]: Stopped Volumio Backend Module. Aug 05 18:55:37 volumio systemd[1]: Started Volumio Backend Module. Aug 05 18:55:37 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:39 volumio mpd[15451]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:39 volumio mpd[15451]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:39 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:39 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:39 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:39 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:39 volumio volumio-remote-updater[467]: [2023-08-05 18:55:39] [connect] Successful connection Aug 05 18:55:39 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:39 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:39 volumio volumio[15499]: info: ----- Volumio3 ---- Aug 05 18:55:39 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:39 volumio volumio[15499]: info: ----- System startup ---- Aug 05 18:55:39 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:39 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:40 volumio volumio[15499]: info: MYVOLUMIO Environment detected Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:40 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:40 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:40 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:40 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:40 volumio volumio[15499]: info: Plugin folders cleanup Aug 05 18:55:40 volumio volumio[15499]: info: Scanning into folder /volumio/app/plugins/ Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category audio_interface Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category miscellanea Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category music_service Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category plugins.json Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category system_controller Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category user_interface Aug 05 18:55:40 volumio volumio[15499]: info: Scanning into folder /data/plugins/ Aug 05 18:55:40 volumio volumio[15499]: info: Scanning category music_service Aug 05 18:55:40 volumio volumio[15499]: info: Plugin folders cleanup completed Aug 05 18:55:40 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:40 volumio volumio[15499]: info: ----- Core plugins startup ---- Aug 05 18:55:40 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:40 volumio volumio[15499]: info: Loading plugins from folder /volumio/app/plugins/ Aug 05 18:55:40 volumio volumio[15499]: info: Adding plugin upnp to MyMusic Plugins Aug 05 18:55:40 volumio volumio[15499]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 05 18:55:40 volumio volumio[15499]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 05 18:55:40 volumio volumio[15499]: info: Loading plugins from folder /data/plugins/ Aug 05 18:55:40 volumio volumio[15499]: info: Loading plugin "system"... Aug 05 18:55:40 volumio volumio[15499]: info: Loading plugin "appearance"... Aug 05 18:55:40 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:41 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "network"... Aug 05 18:55:41 volumio volumio[15499]: info: Refreshing Cached IP Addresses Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "services"... Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "alsa_controller"... Aug 05 18:55:41 volumio volumio[15499]: warn: Unable to locate the audio output device Mini-i Series. Please configure a valid output device. Aug 05 18:55:41 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "wizard"... Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "volumio_command_line_client"... Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "upnp"... Aug 05 18:55:41 volumio volumio[15499]: info: [1691254541982] Starting Upmpd Daemon Aug 05 18:55:41 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "my_music"... Aug 05 18:55:41 volumio volumio[15499]: info: Loading plugin "mpd"... Aug 05 18:55:42 volumio sudo[15559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 05 18:55:42 volumio sudo[15561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 05 18:55:42 volumio sudo[15563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 05 18:55:42 volumio sudo[15561]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:42 volumio sudo[15563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:42 volumio sudo[15559]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:42 volumio sudo[15561]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:42 volumio sudo[15559]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:42 volumio volumio[15499]: info: Creating MPD Configuration file Aug 05 18:55:42 volumio volumio[15499]: info: Loading plugin "upnp_browser"... Aug 05 18:55:42 volumio sudo[15570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:42 volumio sudo[15570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:42 volumio sudo[15570]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:42 volumio sudo[15572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:42 volumio sudo[15572]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:42 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:42 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:42 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:42 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:42 volumio volumio[15499]: info: Loading plugin "networkfs"... Aug 05 18:55:43 volumio volumio[15499]: info: Starting Udev Watcher for removable devices Aug 05 18:55:43 volumio sudo[15580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Bartek,password=Hasl0_dla_B4rtka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Multimedia /mnt/NAS/NAS_Multimedia Aug 05 18:55:43 volumio sudo[15580]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:43 volumio sudo[15580]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:43 volumio kernel: CIFS: Attempting to mount //192.168.2.98/Multimedia Aug 05 18:55:43 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 05 18:55:43 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 05 18:55:43 volumio sudo[15563]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:43 volumio volumio[15499]: info: Ignoring mount for partition: boot Aug 05 18:55:43 volumio volumio[15499]: info: Ignoring mount for partition: volumio Aug 05 18:55:43 volumio volumio[15499]: info: Ignoring mount for partition: volumio_data Aug 05 18:55:43 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "alarm-clock"... Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "airplay_emulation"... Aug 05 18:55:43 volumio volumio[15499]: info: Starting Shairport Sync Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "last_100"... Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "webradio"... Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "i2s_dacs"... Aug 05 18:55:43 volumio volumio[15499]: info: I2S DAC not set, start Auto-detection Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "volumiodiscovery"... Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:43 volumio node[15499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** For more information see Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:43 volumio volumio[15499]: *** WARNING *** For more information see Aug 05 18:55:43 volumio node[15499]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:43 volumio node[15499]: *** WARNING *** For more information see Aug 05 18:55:43 volumio node[15499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:43 volumio node[15499]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:43 volumio node[15499]: *** WARNING *** For more information see Aug 05 18:55:43 volumio volumio[15499]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 05 18:55:43 volumio volumio[15499]: info: Discovery: Started advertising with name: Volumio Aug 05 18:55:43 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:43 volumio volumio[15499]: info: Loading plugin "youtube2"... Aug 05 18:55:44 volumio mpd[15577]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:44 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:44 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:44 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:44 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:44 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:45 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:45 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:45 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:45 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:45 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:46 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:46 volumio mpd[15577]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:46 volumio mpd[15577]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:47 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:47 volumio sudo[15572]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "outputs"... Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "albumart"... Aug 05 18:55:48 volumio volumio[15499]: info: Plugin example_plugin is not enabled Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "inputs"... Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "updater_comm"... Aug 05 18:55:48 volumio volumio[15499]: info: Plugin mpdemulation is not enabled Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "rest_api"... Aug 05 18:55:48 volumio volumio[15499]: info: Loading plugin "websocket"... Aug 05 18:55:48 volumio volumio[15499]: info: Plugin spop is not enabled Aug 05 18:55:48 volumio volumio[15499]: info: Loading i18n strings for locale pl Aug 05 18:55:48 volumio volumio[15499]: Updating browse sources language Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::initPlayerControls Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: Forking 3 albumart workers Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: Express server listening on port 3000 Aug 05 18:55:48 volumio volumio[15499]: [Metrics] WebUI: 9s 424.51ms Aug 05 18:55:48 volumio volumio[15499]: info: CoreStateMachine::resetVolumioState Aug 05 18:55:48 volumio volumio[15499]: info: CoreStateMachine::getcurrentVolume Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:48 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:48 volumio volumio[15499]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:48 volumio volumio[15499]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:55:48 volumio volumio[15499]: info: MPD Permissions set Aug 05 18:55:49 volumio volumio[15499]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::pushState Aug 05 18:55:49 volumio volumio[15499]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::updateTrackBlock Aug 05 18:55:49 volumio volumio[15499]: info: CorePlayQueue::getTrackBlock Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:55:49 volumio volumio[15499]: info: MPD running with PID15577 Aug 05 18:55:49 volumio volumio[15499]: ,establishing connection Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: Reloading queue from file Aug 05 18:55:49 volumio volumio[15499]: error: updateQueue error: null Aug 05 18:55:49 volumio volumio[15499]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::pushState Aug 05 18:55:49 volumio volumio[15499]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::setRandom null Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::pushState Aug 05 18:55:49 volumio volumio[15499]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:49 volumio volumio[15499]: info: Setting Device type: Raspberry PI Aug 05 18:55:49 volumio volumio[15499]: error: updateQueue error: null Aug 05 18:55:49 volumio volumio[15499]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:55:49 volumio volumio[15499]: info: CoreStateMachine::pushState Aug 05 18:55:49 volumio volumio[15499]: info: CorePlayQueue::getTrack 0 Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioPushState Aug 05 18:55:49 volumio volumio[15499]: info: Completed loading Core Plugins Aug 05 18:55:49 volumio volumio[15499]: info: Preparing to generate the ALSA configuration file Aug 05 18:55:49 volumio volumio[15499]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:55:49 volumio volumio[15499]: info: Output device has changed, restarting MPD Aug 05 18:55:49 volumio volumio[15499]: info: Output device has changed, restarting Shairport Sync Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:49 volumio volumio[15499]: info: ___________ START PLUGINS ___________ Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:49 volumio volumio[15499]: info: [1691254549609] CoreMusicLibrary::Adding element Media Servers Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:49 volumio volumio[15499]: Starting albumart workers Aug 05 18:55:49 volumio volumio[15499]: Starting albumart workers Aug 05 18:55:49 volumio volumio[15499]: Starting albumart workers Aug 05 18:55:49 volumio sudo[15668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:49 volumio sudo[15668]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:49 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:49 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:49 volumio sudo[15668]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:49 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:49 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:49 volumio sudo[15670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:49 volumio sudo[15670]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:49 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:49 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:50 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:50 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:50 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:50 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:50 volumio volumio[15499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:55:50 volumio volumio[15499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:50 volumio volumio[15499]: info: [1691254550286] CoreMusicLibrary::Adding element Last_100 Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:50 volumio volumio[15499]: info: [1691254550313] CoreMusicLibrary::Adding element Webradio Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:50 volumio volumio[15499]: info: [youtube2-auth] Starting auth flow... Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:55:50 volumio volumio[15499]: info: [1691254550497] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:55:50 volumio volumio[15499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:55:50 volumio volumio[15499]: Cannot find translation for source YouTube2 Aug 05 18:55:50 volumio volumio[15499]: info: Volumio Calling Home Aug 05 18:55:50 volumio volumio[15499]: info: Completed starting Core Plugins Aug 05 18:55:50 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:50 volumio volumio[15499]: info: ----- MyVolumio plugins startup ---- Aug 05 18:55:50 volumio volumio[15499]: info: ------------------------------------------- Aug 05 18:55:50 volumio volumio[15499]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:55:50 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:50 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c0:a8 Aug 05 18:55:50 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c0:a8 completed [id=0 id_str=] Aug 05 18:55:50 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:50 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:50 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:50 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:50 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:50 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:50 volumio volumio[15499]: Unhandled rejection Error: No sockets available, cannot start. Aug 05 18:55:50 volumio volumio[15499]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 05 18:55:50 volumio volumio[15499]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 05 18:55:50 volumio volumio[15499]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 05 18:55:50 volumio volumio[15499]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 05 18:55:50 volumio volumio[15499]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 05 18:55:50 volumio volumio[15499]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 05 18:55:50 volumio volumio[15499]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 05 18:55:50 volumio volumio[15499]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 05 18:55:50 volumio volumio[15499]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 05 18:55:50 volumio volumio[15499]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 05 18:55:50 volumio volumio[15499]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 05 18:55:50 volumio volumio[15499]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 05 18:55:50 volumio volumio[15499]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 05 18:55:50 volumio volumio[15499]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 05 18:55:50 volumio volumio[15499]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 05 18:55:50 volumio volumio[15499]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 05 18:55:50 volumio volumio[15499]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:50 volumio volumio[15499]: ReferenceError: deferred is not defined Aug 05 18:55:50 volumio volumio[15499]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:55:50 volumio volumio[15499]: at ClientRequest.emit (events.js:315:20) Aug 05 18:55:50 volumio volumio[15499]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:55:50 volumio volumio[15499]: at TLSSocket.emit (events.js:315:20) Aug 05 18:55:50 volumio volumio[15499]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:55:50 volumio volumio[15499]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:55:50 volumio volumio[15499]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:55:50 volumio volumio[15499]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:55:51 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:51 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:51 volumio mpd[15704]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:55:52 volumio sudo[15755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:54 Aug 05 18:55:52 volumio sudo[15755]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:52 volumio sudo[15755]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 05 18:55:52 volumio sudo[15670]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 05 18:55:52 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:52 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:52 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 05 18:55:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 57. Aug 05 18:55:52 volumio systemd[1]: Started dynamicswap service. Aug 05 18:55:52 volumio systemd[1]: Stopped Volumio Backend Module. Aug 05 18:55:52 volumio systemd[1]: Started Volumio Backend Module. Aug 05 18:55:52 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:55:54 volumio mpd[15704]: Aug 05 18:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:55:54 volumio mpd[15704]: Aug 05 18:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:55:54 volumio volumio-remote-updater[467]: [2023-08-05 18:55:54] [connect] Successful connection Aug 05 18:55:54 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:55:54 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:55:54 volumio volumio[15769]: info: ----- Volumio3 ---- Aug 05 18:55:54 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:55:54 volumio volumio[15769]: info: ----- System startup ---- Aug 05 18:55:54 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:55:55 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a8:5e:45:fb:c0:a8 reason=0 locally_generated=1 Aug 05 18:55:55 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:55 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 05 18:55:55 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 05 18:55:55 volumio volumio[15769]: info: MYVOLUMIO Environment detected Aug 05 18:55:55 volumio volumio[15769]: info: Plugin folders cleanup Aug 05 18:55:55 volumio volumio[15769]: info: Scanning into folder /volumio/app/plugins/ Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category audio_interface Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category miscellanea Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category music_service Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category plugins.json Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category system_controller Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category user_interface Aug 05 18:55:55 volumio volumio[15769]: info: Scanning into folder /data/plugins/ Aug 05 18:55:55 volumio volumio[15769]: info: Scanning category music_service Aug 05 18:55:55 volumio volumio[15769]: info: Plugin folders cleanup completed Aug 05 18:55:55 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:55:55 volumio volumio[15769]: info: ----- Core plugins startup ---- Aug 05 18:55:55 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:55:55 volumio volumio[15769]: info: Loading plugins from folder /volumio/app/plugins/ Aug 05 18:55:55 volumio wpa_supplicant[746]: wlan0: Trying to associate with SSID 'ASUS_Kopec' Aug 05 18:55:55 volumio volumio[15769]: info: Adding plugin upnp to MyMusic Plugins Aug 05 18:55:55 volumio volumio[15769]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 05 18:55:55 volumio volumio[15769]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 05 18:55:55 volumio volumio[15769]: info: Loading plugins from folder /data/plugins/ Aug 05 18:55:55 volumio volumio[15769]: info: Loading plugin "system"... Aug 05 18:55:55 volumio volumio[15769]: info: Loading plugin "appearance"... Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "network"... Aug 05 18:55:56 volumio volumio[15769]: info: Refreshing Cached IP Addresses Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "services"... Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "alsa_controller"... Aug 05 18:55:56 volumio volumio[15769]: warn: Unable to locate the audio output device Mini-i Series. Please configure a valid output device. Aug 05 18:55:56 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "wizard"... Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "volumio_command_line_client"... Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "upnp"... Aug 05 18:55:56 volumio volumio[15769]: info: [1691254556965] Starting Upmpd Daemon Aug 05 18:55:56 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "my_music"... Aug 05 18:55:56 volumio volumio[15769]: info: Loading plugin "mpd"... Aug 05 18:55:57 volumio volumio[15769]: info: Creating MPD Configuration file Aug 05 18:55:57 volumio volumio[15769]: info: Loading plugin "upnp_browser"... Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:57 volumio wpa_supplicant[746]: wlan0: Associated with a8:5e:45:fb:c1:31 Aug 05 18:55:57 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:fb:c1:31 completed [id=0 id_str=] Aug 05 18:55:57 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: carrier lost Aug 05 18:55:57 volumio sudo[15817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 05 18:55:57 volumio sudo[15817]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:57 volumio sudo[15815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 05 18:55:57 volumio sudo[15815]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: carrier acquired Aug 05 18:55:57 volumio wpa_supplicant[746]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: IAID eb:02:7e:40 Aug 05 18:55:57 volumio sudo[15825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:55:57 volumio sudo[15815]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:57 volumio sudo[15817]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:57 volumio sudo[15819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 05 18:55:57 volumio sudo[15825]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:57 volumio sudo[15819]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:57 volumio sudo[15823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:55:57 volumio sudo[15823]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:57 volumio sudo[15823]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:57 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:55:57 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:55:57 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:55:57 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:55:57 volumio dhcpcd[758]: wlan0: soliciting an IPv6 router Aug 05 18:55:57 volumio volumio[15769]: info: Loading plugin "networkfs"... Aug 05 18:55:58 volumio dhcpcd[758]: wlan0: soliciting a DHCP lease Aug 05 18:55:58 volumio volumio[15769]: info: Starting Udev Watcher for removable devices Aug 05 18:55:58 volumio sudo[15850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Bartek,password=Hasl0_dla_B4rtka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Multimedia /mnt/NAS/NAS_Multimedia Aug 05 18:55:58 volumio sudo[15850]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:55:58 volumio sudo[15850]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:58 volumio kernel: CIFS: Attempting to mount //192.168.2.98/Multimedia Aug 05 18:55:58 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 05 18:55:58 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 05 18:55:58 volumio sudo[15819]: pam_unix(sudo:session): session closed for user root Aug 05 18:55:58 volumio volumio[15769]: info: Ignoring mount for partition: boot Aug 05 18:55:58 volumio volumio[15769]: info: Ignoring mount for partition: volumio Aug 05 18:55:58 volumio volumio[15769]: info: Ignoring mount for partition: volumio_data Aug 05 18:55:58 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "alarm-clock"... Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "airplay_emulation"... Aug 05 18:55:58 volumio volumio[15769]: info: Starting Shairport Sync Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "last_100"... Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "webradio"... Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "i2s_dacs"... Aug 05 18:55:58 volumio volumio[15769]: info: I2S DAC not set, start Auto-detection Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "volumiodiscovery"... Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** For more information see Aug 05 18:55:58 volumio node[15769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:58 volumio volumio[15769]: *** WARNING *** For more information see Aug 05 18:55:58 volumio node[15769]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:58 volumio node[15769]: *** WARNING *** For more information see Aug 05 18:55:58 volumio node[15769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:55:58 volumio node[15769]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:55:58 volumio node[15769]: *** WARNING *** For more information see Aug 05 18:55:58 volumio volumio[15769]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 05 18:55:58 volumio volumio[15769]: info: Discovery: Started advertising with name: Volumio Aug 05 18:55:58 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:55:58 volumio volumio[15769]: info: Loading plugin "youtube2"... Aug 05 18:55:59 volumio mpd[15847]: Aug 05 18:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:56:01 volumio mpd[15847]: Aug 05 18:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:56:01 volumio mpd[15847]: Aug 05 18:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:56:02 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:56:02 volumio sudo[15825]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:03 volumio dhcpcd[758]: wlan0: probing for an IPv4LL address Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "outputs"... Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "albumart"... Aug 05 18:56:03 volumio volumio[15769]: info: Plugin example_plugin is not enabled Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "inputs"... Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "updater_comm"... Aug 05 18:56:03 volumio volumio[15769]: info: Plugin mpdemulation is not enabled Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "rest_api"... Aug 05 18:56:03 volumio volumio[15769]: info: Loading plugin "websocket"... Aug 05 18:56:03 volumio volumio[15769]: info: Plugin spop is not enabled Aug 05 18:56:03 volumio volumio[15769]: info: Loading i18n strings for locale pl Aug 05 18:56:03 volumio volumio[15769]: Updating browse sources language Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::initPlayerControls Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:03 volumio volumio[15769]: Express server listening on port 3000 Aug 05 18:56:03 volumio volumio[15769]: [Metrics] WebUI: 9s 562.69ms Aug 05 18:56:03 volumio volumio[15769]: Forking 3 albumart workers Aug 05 18:56:03 volumio volumio[15769]: info: CoreStateMachine::resetVolumioState Aug 05 18:56:03 volumio volumio[15769]: info: CoreStateMachine::getcurrentVolume Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:56:03 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:56:04 volumio volumio[15769]: info: MPD Permissions set Aug 05 18:56:04 volumio volumio[15769]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:56:04 volumio volumio[15769]: info: MPD running with PID15847 Aug 05 18:56:04 volumio volumio[15769]: ,establishing connection Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: Reloading queue from file Aug 05 18:56:04 volumio volumio[15769]: error: updateQueue error: null Aug 05 18:56:04 volumio volumio[15769]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::pushState Aug 05 18:56:04 volumio volumio[15769]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::updateTrackBlock Aug 05 18:56:04 volumio volumio[15769]: info: CorePlayQueue::getTrackBlock Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::pushState Aug 05 18:56:04 volumio volumio[15769]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::setRandom null Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::pushState Aug 05 18:56:04 volumio volumio[15769]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:04 volumio volumio[15769]: info: Setting Device type: Raspberry PI Aug 05 18:56:04 volumio volumio[15769]: error: updateQueue error: null Aug 05 18:56:04 volumio volumio[15769]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:56:04 volumio volumio[15769]: info: Completed loading Core Plugins Aug 05 18:56:04 volumio volumio[15769]: info: Preparing to generate the ALSA configuration file Aug 05 18:56:04 volumio volumio[15769]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:56:04 volumio volumio[15769]: info: CoreStateMachine::pushState Aug 05 18:56:04 volumio volumio[15769]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:04 volumio volumio[15769]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:56:04 volumio volumio[15769]: info: Output device has changed, restarting MPD Aug 05 18:56:04 volumio volumio[15769]: info: Output device has changed, restarting Shairport Sync Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:04 volumio volumio[15769]: info: ___________ START PLUGINS ___________ Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:04 volumio volumio[15769]: info: [1691254564664] CoreMusicLibrary::Adding element Media Servers Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:04 volumio sudo[15905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:56:04 volumio sudo[15906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:56:04 volumio sudo[15906]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:04 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:04 volumio sudo[15905]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:56:04 volumio sudo[15905]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:04 volumio volumio[15769]: Starting albumart workers Aug 05 18:56:04 volumio volumio[15769]: Starting albumart workers Aug 05 18:56:04 volumio volumio[15769]: Starting albumart workers Aug 05 18:56:05 volumio volumio[15769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:05 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:56:05 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:56:05 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:56:05 volumio volumio[15769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:05 volumio volumio[15769]: info: [1691254565326] CoreMusicLibrary::Adding element Last_100 Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:05 volumio volumio[15769]: info: [1691254565331] CoreMusicLibrary::Adding element Webradio Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:56:05 volumio ntpd[642]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 05 18:56:05 volumio volumio[15769]: info: [youtube2-auth] Starting auth flow... Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:05 volumio volumio[15769]: info: [1691254565586] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:56:05 volumio volumio[15769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:05 volumio volumio[15769]: Cannot find translation for source YouTube2 Aug 05 18:56:05 volumio volumio[15769]: info: Volumio Calling Home Aug 05 18:56:05 volumio volumio[15769]: info: Completed starting Core Plugins Aug 05 18:56:05 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:56:05 volumio volumio[15769]: info: ----- MyVolumio plugins startup ---- Aug 05 18:56:05 volumio volumio[15769]: info: ------------------------------------------- Aug 05 18:56:05 volumio volumio[15769]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:56:05 volumio volumio[15769]: Unhandled rejection Error: No sockets available, cannot start. Aug 05 18:56:05 volumio volumio[15769]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 05 18:56:05 volumio volumio[15769]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 05 18:56:05 volumio volumio[15769]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 05 18:56:05 volumio volumio[15769]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 05 18:56:05 volumio volumio[15769]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 05 18:56:05 volumio volumio[15769]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 05 18:56:05 volumio volumio[15769]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 05 18:56:05 volumio volumio[15769]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 05 18:56:05 volumio volumio[15769]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 05 18:56:05 volumio volumio[15769]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 05 18:56:05 volumio volumio[15769]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 05 18:56:05 volumio volumio[15769]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 05 18:56:05 volumio volumio[15769]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 05 18:56:05 volumio volumio[15769]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 05 18:56:05 volumio volumio[15769]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 05 18:56:05 volumio volumio[15769]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 05 18:56:05 volumio volumio[15769]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:56:05 volumio volumio[15769]: ReferenceError: deferred is not defined Aug 05 18:56:05 volumio volumio[15769]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:56:05 volumio volumio[15769]: at ClientRequest.emit (events.js:315:20) Aug 05 18:56:05 volumio volumio[15769]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:56:05 volumio volumio[15769]: at TLSSocket.emit (events.js:315:20) Aug 05 18:56:05 volumio volumio[15769]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:56:05 volumio volumio[15769]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:56:05 volumio volumio[15769]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:56:05 volumio volumio[15769]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:56:06 volumio mpd[15920]: Aug 05 18:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:56:07 volumio sudo[15957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:55 Aug 05 18:56:07 volumio sudo[15957]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:07 volumio sudo[15957]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 05 18:56:07 volumio sudo[15906]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 05 18:56:07 volumio systemd[1]: Started dynamicswap service. Aug 05 18:56:07 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:56:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 05 18:56:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 58. Aug 05 18:56:07 volumio systemd[1]: Stopped Volumio Backend Module. Aug 05 18:56:07 volumio systemd[1]: Started Volumio Backend Module. Aug 05 18:56:07 volumio systemd[1]: Started dynamicswap service. Aug 05 18:56:07 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 05 18:56:08 volumio dhcpcd[758]: wlan0: using IPv4LL address 169.254.36.20 Aug 05 18:56:09 volumio mpd[15920]: Aug 05 18:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:56:09 volumio mpd[15920]: Aug 05 18:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:56:09 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.36.20. Aug 05 18:56:09 volumio dhcpcd[758]: wlan0: adding route to 169.254.0.0/16 Aug 05 18:56:09 volumio volumio-remote-updater[467]: [2023-08-05 18:56:09] [connect] Successful connection Aug 05 18:56:09 volumio dhcpcd[758]: wlan0: adding default route Aug 05 18:56:09 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. Aug 05 18:56:09 volumio avahi-daemon[487]: Registering new address record for 169.254.36.20 on wlan0.IPv4. Aug 05 18:56:09 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:56:09 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:09 volumio volumio[15970]: info: ----- Volumio3 ---- Aug 05 18:56:09 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:09 volumio volumio[15970]: info: ----- System startup ---- Aug 05 18:56:09 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:10 volumio volumio[15970]: info: MYVOLUMIO Environment detected Aug 05 18:56:10 volumio dhcpcd[758]: wlan0: offered 192.168.2.5 from 192.168.2.1 Aug 05 18:56:10 volumio volumio[15970]: info: Plugin folders cleanup Aug 05 18:56:10 volumio volumio[15970]: info: Scanning into folder /volumio/app/plugins/ Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category audio_interface Aug 05 18:56:10 volumio dhcpcd[758]: wlan0: probing address 192.168.2.5/24 Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category miscellanea Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category music_service Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category plugins.json Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category system_controller Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category user_interface Aug 05 18:56:10 volumio volumio[15970]: info: Scanning into folder /data/plugins/ Aug 05 18:56:10 volumio volumio[15970]: info: Scanning category music_service Aug 05 18:56:10 volumio volumio[15970]: info: Plugin folders cleanup completed Aug 05 18:56:10 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:10 volumio volumio[15970]: info: ----- Core plugins startup ---- Aug 05 18:56:10 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:10 volumio volumio[15970]: info: Loading plugins from folder /volumio/app/plugins/ Aug 05 18:56:10 volumio volumio[15970]: info: Adding plugin upnp to MyMusic Plugins Aug 05 18:56:10 volumio volumio[15970]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 05 18:56:10 volumio volumio[15970]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 05 18:56:10 volumio volumio[15970]: info: Loading plugins from folder /data/plugins/ Aug 05 18:56:10 volumio volumio[15970]: info: Loading plugin "system"... Aug 05 18:56:10 volumio volumio[15970]: info: Loading plugin "appearance"... Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "network"... Aug 05 18:56:11 volumio volumio[15970]: info: Refreshing Cached IP Addresses Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "services"... Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "alsa_controller"... Aug 05 18:56:11 volumio volumio[15970]: warn: Unable to locate the audio output device Mini-i Series. Please configure a valid output device. Aug 05 18:56:11 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "wizard"... Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "volumio_command_line_client"... Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "upnp"... Aug 05 18:56:11 volumio volumio[15970]: info: [1691254571955] Starting Upmpd Daemon Aug 05 18:56:11 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "my_music"... Aug 05 18:56:11 volumio volumio[15970]: info: Loading plugin "mpd"... Aug 05 18:56:12 volumio volumio[15970]: info: Creating MPD Configuration file Aug 05 18:56:12 volumio volumio[15970]: info: Loading plugin "upnp_browser"... Aug 05 18:56:12 volumio ntpd[642]: Listen normally on 3 wlan0 169.254.36.20:123 Aug 05 18:56:12 volumio ntpd[642]: new interface(s) found: waking up resolver Aug 05 18:56:12 volumio sudo[16009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 05 18:56:12 volumio sudo[16007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 05 18:56:12 volumio sudo[16007]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:12 volumio sudo[16015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:56:12 volumio sudo[16011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 05 18:56:12 volumio sudo[16011]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:12 volumio sudo[16015]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:12 volumio sudo[16009]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:12 volumio sudo[16015]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:12 volumio sudo[16017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:56:12 volumio sudo[16007]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:12 volumio sudo[16009]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:12 volumio sudo[16017]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:12 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:56:12 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:56:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:56:12 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:56:12 volumio volumio[15970]: info: Loading plugin "networkfs"... Aug 05 18:56:12 volumio volumio[15970]: info: Starting Udev Watcher for removable devices Aug 05 18:56:13 volumio sudo[16028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Bartek,password=Hasl0_dla_B4rtka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Multimedia /mnt/NAS/NAS_Multimedia Aug 05 18:56:13 volumio sudo[16028]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:13 volumio kernel: CIFS: Attempting to mount //192.168.2.98/Multimedia Aug 05 18:56:13 volumio volumio[15970]: info: Ignoring mount for partition: boot Aug 05 18:56:13 volumio volumio[15970]: info: Ignoring mount for partition: volumio Aug 05 18:56:13 volumio volumio[15970]: info: Ignoring mount for partition: volumio_data Aug 05 18:56:13 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "alarm-clock"... Aug 05 18:56:13 volumio sudo[16011]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "airplay_emulation"... Aug 05 18:56:13 volumio volumio[15970]: info: Starting Shairport Sync Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "last_100"... Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "webradio"... Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "i2s_dacs"... Aug 05 18:56:13 volumio volumio[15970]: info: I2S DAC not set, start Auto-detection Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "volumiodiscovery"... Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:56:13 volumio node[15970]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:56:13 volumio node[15970]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** For more information see Aug 05 18:56:13 volumio node[15970]: *** WARNING *** For more information see Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:56:13 volumio node[15970]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:56:13 volumio node[15970]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 05 18:56:13 volumio volumio[15970]: *** WARNING *** For more information see Aug 05 18:56:13 volumio node[15970]: *** WARNING *** For more information see Aug 05 18:56:13 volumio volumio[15970]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 05 18:56:13 volumio volumio[15970]: info: Discovery: Started advertising with name: Volumio Aug 05 18:56:13 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 05 18:56:13 volumio volumio[15970]: info: Loading plugin "youtube2"... Aug 05 18:56:14 volumio mpd[16026]: Aug 05 18:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:56:15 volumio dhcpcd[758]: wlan0: leased 192.168.2.5 for 86400 seconds Aug 05 18:56:15 volumio avahi-daemon[487]: Registering new address record for 192.168.2.5 on wlan0.IPv4. Aug 05 18:56:15 volumio dhcpcd[758]: wlan0: adding route to 192.168.2.0/24 Aug 05 18:56:15 volumio dhcpcd[758]: wlan0: changing default route via 192.168.2.1 Aug 05 18:56:15 volumio avahi-daemon[487]: Withdrawing address record for 169.254.36.20 on wlan0. Aug 05 18:56:15 volumio avahi-daemon[487]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.36.20. Aug 05 18:56:15 volumio dhcpcd[758]: wlan0: deleting route to 169.254.0.0/16 Aug 05 18:56:15 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.5. Aug 05 18:56:15 volumio dhcpcd[758]: wlan0: pid 758 deleted default route via 192.168.2.1 Aug 05 18:56:16 volumio mpd[16026]: Aug 05 18:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:56:16 volumio mpd[16026]: Aug 05 18:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:56:17 volumio ntpd[642]: Listen normally on 4 wlan0 192.168.2.5:123 Aug 05 18:56:17 volumio ntpd[642]: Deleting interface #3 wlan0, 169.254.36.20#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs Aug 05 18:56:17 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:56:17 volumio ntpd[642]: new interface(s) found: waking up resolver Aug 05 18:56:17 volumio sudo[16017]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:17 volumio ntpd[642]: Soliciting pool server 193.59.134.156 Aug 05 18:56:17 volumio ntpd[642]: Soliciting pool server 212.127.78.21 Aug 05 18:56:18 volumio sudo[16028]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:18 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -112 Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "outputs"... Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "albumart"... Aug 05 18:56:18 volumio volumio[15970]: info: Plugin example_plugin is not enabled Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "inputs"... Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "updater_comm"... Aug 05 18:56:18 volumio ntpd[642]: Soliciting pool server 193.70.94.182 Aug 05 18:56:18 volumio ntpd[642]: Soliciting pool server 162.159.200.123 Aug 05 18:56:18 volumio volumio[15970]: info: Plugin mpdemulation is not enabled Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "rest_api"... Aug 05 18:56:18 volumio volumio[15970]: info: Loading plugin "websocket"... Aug 05 18:56:18 volumio volumio[15970]: info: Plugin spop is not enabled Aug 05 18:56:18 volumio volumio[15970]: info: Loading i18n strings for locale pl Aug 05 18:56:18 volumio volumio[15970]: Updating browse sources language Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::initPlayerControls Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: Express server listening on port 3000 Aug 05 18:56:18 volumio volumio[15970]: Forking 3 albumart workers Aug 05 18:56:18 volumio volumio[15970]: [Metrics] WebUI: 9s 435.99ms Aug 05 18:56:18 volumio volumio[15970]: info: CoreStateMachine::resetVolumioState Aug 05 18:56:18 volumio volumio[15970]: info: CoreStateMachine::getcurrentVolume Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:56:18 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:18 volumio volumio[15970]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:56:18 volumio volumio[15970]: info: Cannot mount NAS NAS_Multimedia at system boot, trial number 1 ,retrying in 5 seconds Aug 05 18:56:18 volumio volumio[15970]: info: MPD Permissions set Aug 05 18:56:19 volumio volumio[15970]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::pushState Aug 05 18:56:19 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::updateTrackBlock Aug 05 18:56:19 volumio volumio[15970]: info: CorePlayQueue::getTrackBlock Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioRetrievevolume Aug 05 18:56:19 volumio volumio[15970]: info: MPD running with PID16026 Aug 05 18:56:19 volumio volumio[15970]: ,establishing connection Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: Reloading queue from file Aug 05 18:56:19 volumio volumio[15970]: error: updateQueue error: null Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::setRepeat false single undefined Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::pushState Aug 05 18:56:19 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::setRandom null Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::pushState Aug 05 18:56:19 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:19 volumio volumio[15970]: info: Setting Device type: Raspberry PI Aug 05 18:56:19 volumio volumio[15970]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 05 18:56:19 volumio volumio[15970]: error: updateQueue error: null Aug 05 18:56:19 volumio volumio[15970]: info: Completed loading Core Plugins Aug 05 18:56:19 volumio volumio[15970]: info: Preparing to generate the ALSA configuration file Aug 05 18:56:19 volumio ntpd[642]: Soliciting pool server 213.222.217.10 Aug 05 18:56:19 volumio ntpd[642]: Soliciting pool server 89.161.47.136 Aug 05 18:56:19 volumio volumio[15970]: info: VolumeController:: Volume=undefined Mute =false Aug 05 18:56:19 volumio volumio[15970]: info: CoreStateMachine::pushState Aug 05 18:56:19 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioPushState Aug 05 18:56:19 volumio volumio[15970]: info: Asound.conf file unchanged, so no further update is needed Aug 05 18:56:19 volumio volumio[15970]: info: Output device has changed, restarting MPD Aug 05 18:56:19 volumio volumio[15970]: info: Output device has changed, restarting Shairport Sync Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:19 volumio volumio[15970]: info: ___________ START PLUGINS ___________ Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:19 volumio volumio[15970]: info: [1691254579587] CoreMusicLibrary::Adding element Media Servers Aug 05 18:56:19 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:19 volumio volumio[15970]: Starting albumart workers Aug 05 18:56:19 volumio volumio[15970]: Starting albumart workers Aug 05 18:56:19 volumio volumio[15970]: Starting albumart workers Aug 05 18:56:19 volumio sudo[16130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 05 18:56:19 volumio sudo[16128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 05 18:56:19 volumio sudo[16128]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:19 volumio sudo[16128]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:19 volumio sudo[16130]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:20 volumio systemd[1]: Stopping Music Player Daemon... Aug 05 18:56:20 volumio volumio[15970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:20 volumio systemd[1]: mpd.service: Succeeded. Aug 05 18:56:20 volumio systemd[1]: Stopped Music Player Daemon. Aug 05 18:56:20 volumio systemd[1]: Starting Music Player Daemon... Aug 05 18:56:20 volumio volumio[15970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:20 volumio volumio[15970]: info: [1691254580276] CoreMusicLibrary::Adding element Last_100 Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:20 volumio volumio[15970]: info: [1691254580282] CoreMusicLibrary::Adding element Webradio Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 05 18:56:20 volumio volumio[15970]: info: [youtube2-auth] Starting auth flow... Aug 05 18:56:20 volumio ntpd[642]: Soliciting pool server 149.156.70.60 Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 05 18:56:20 volumio volumio[15970]: info: [1691254580450] CoreMusicLibrary::Adding element YouTube2 Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 05 18:56:20 volumio volumio[15970]: Cannot find translation for source YouTube2 Aug 05 18:56:20 volumio volumio[15970]: info: Volumio Calling Home Aug 05 18:56:20 volumio volumio[15970]: info: Completed starting Core Plugins Aug 05 18:56:20 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:20 volumio volumio[15970]: info: ----- MyVolumio plugins startup ---- Aug 05 18:56:20 volumio volumio[15970]: info: ------------------------------------------- Aug 05 18:56:20 volumio volumio[15970]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 05 18:56:20 volumio volumio[15970]: info: Discovery: adding f5e7e061-5dd7-45e3-bf6a-31fc6341f27c Aug 05 18:56:20 volumio volumio[15970]: info: Discovery: Found device Volumio Aug 05 18:56:20 volumio volumio[15970]: info: CoreCommandRouter::volumioGetState Aug 05 18:56:20 volumio volumio[15970]: info: CorePlayQueue::getTrack 0 Aug 05 18:56:20 volumio volumio[15970]: info: MPD Permissions set Aug 05 18:56:20 volumio volumio[15970]: info: Volumio called home Aug 05 18:56:21 volumio volumio[15970]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 05 18:56:21 volumio volumio[15970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:56:21 volumio volumio[15970]: ReferenceError: deferred is not defined Aug 05 18:56:21 volumio volumio[15970]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 05 18:56:21 volumio volumio[15970]: at ClientRequest.emit (events.js:315:20) Aug 05 18:56:21 volumio volumio[15970]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 05 18:56:21 volumio volumio[15970]: at TLSSocket.emit (events.js:315:20) Aug 05 18:56:21 volumio volumio[15970]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 05 18:56:21 volumio volumio[15970]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 05 18:56:21 volumio volumio[15970]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 05 18:56:21 volumio volumio[15970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 05 18:56:21 volumio ntpd[642]: Soliciting pool server 2a0d:5440::24 Aug 05 18:56:21 volumio mpd[16148]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 05 18:56:21 volumio mpd[16148]: output: No 'audio_output' defined in config file Aug 05 18:56:21 volumio mpd[16148]: output: Attempt to detect audio output device Aug 05 18:56:21 volumio mpd[16148]: output: Attempting to detect a alsa audio device Aug 05 18:56:21 volumio mpd[16148]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Aug 05 18:56:21 volumio mpd[16148]: alsa_output: Error opening default ALSA device: No such device Aug 05 18:56:21 volumio mpd[16148]: output: Attempting to detect a oss audio device Aug 05 18:56:21 volumio mpd[16148]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Aug 05 18:56:21 volumio mpd[16148]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Aug 05 18:56:21 volumio mpd[16148]: output: Attempting to detect a pulse audio device Aug 05 18:56:21 volumio mpd[16148]: output: Attempting to detect a jack audio device Aug 05 18:56:21 volumio mpd[16148]: output: Successfully detected a jack audio device Aug 05 18:56:21 volumio mpd[16148]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 05 18:56:21 volumio mpd[16148]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 05 18:56:22 volumio mpd[16148]: zeroconf: No global port, disabling zeroconf Aug 05 18:56:22 volumio systemd[1]: Started Music Player Daemon. Aug 05 18:56:22 volumio sudo[16130]: pam_unix(sudo:session): session closed for user root Aug 05 18:56:22 volumio sudo[16184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-05 18:55 Aug 05 18:56:22 volumio sudo[16184]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"